Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.021Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://www.googleapis.com duration=113.188177ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.036Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://securetoken.googleapis.com duration=128.442552ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.074Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://functions.volumio.cloud duration=164.691667ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.074Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://functions.volumio.cloud duration=163.701094ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.190Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://database.volumio.cloud duration=280.42026ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.191Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://google.com duration=283.328906ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.272Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=362.519583ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.295Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=384.575468ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.302Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=http://cddb.volumio.org duration=393.256354ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.319Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=409.414479ms Aug 28 13:37:00 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:00.418Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.201658ms timeout=10s endpoint=http://plugins.volumio.org duration=509.1475ms Aug 28 13:37:00 volumio sudo[3856]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 13:37:00 volumio sudo[3856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:00 volumio sudo[3856]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:00 volumio sudo[3858]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 13:37:00 volumio sudo[3858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:00 volumio sudo[3858]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:00 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.76 UA: Mozilla/5.0 (Linux; Android 16; motorola edge 60 pro Build/W1VVS36H.7-108-8-6; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 28 13:37:01 volumio sudo[3862]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 13:37:01 volumio sudo[3862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:01 volumio sudo[3864]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 13:37:01 volumio sudo[3862]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:01 volumio sudo[3864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:01 volumio sudo[3864]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:01 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.76 UA: Mozilla/5.0 (Linux; Android 16; motorola edge 60 pro Build/W1VVS36H.7-108-8-6; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::volumioGetQueue Aug 28 13:37:01 volumio volumio[1144]: info: CoreStateMachine::getQueue Aug 28 13:37:01 volumio volumio[1144]: info: CorePlayQueue::getQueue Aug 28 13:37:01 volumio volumio[1144]: info: Listing playlists Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 13:37:01 volumio volumio[1144]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 13:37:01 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:01 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 13:37:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 13:37:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 13:37:03 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:03 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:03 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:03.312Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:03 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:03.313Z level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x18c0180" event=SERVER_EVENT_TYPE_PLAYER_METADATA_CHANGED error="peer is gone" Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 13:37:03 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:03 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:09 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:09.650Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:11 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 28 13:37:13 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:13.025Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:13 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:13.025Z level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x18c0180" event=SERVER_EVENT_TYPE_PLAYER_METADATA_CHANGED error="peer is gone" Aug 28 13:37:13 volumio volumio[1144]: info: CoreCommandRouter::Close All Modals sent Aug 28 13:37:13 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Aug 28 13:37:16 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:16.393Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:16 volumio volumio[1144]: info: VolumeController::SetAlsaVolume57 Aug 28 13:37:16 volumio volumio[1144]: info: CoreStateMachine::pushState Aug 28 13:37:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 13:37:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Aug 28 13:37:16 volumio volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Aug 28 13:37:16 volumio volumio[1144]: info: MRS: Pushing multiroomSync output Aug 28 13:37:16 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:16 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:16.855Z level=WARN msg="received unknown player status" component=volumio status="" Aug 28 13:37:16 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:16.855Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" state=STATUS_STOPPED positionMs=99371 volume=57 Aug 28 13:37:16 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:16.856Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" id= title= Aug 28 13:37:16 volumio volumio[1144]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 13:37:19 volumio volumio[1144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 13:37:19 volumio volumio[1144]: info: Received Get System Version Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 13:37:19 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:19 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:19 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:19 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:19.859Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:20 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 13:37:20 volumio volumio[1144]: info: Preload queue cleared Aug 28 13:37:21 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 13:37:21 volumio volumio[1144]: info: Preload queue cleared Aug 28 13:37:23 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:23.259Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:25 volumio volumio[1144]: info: Preload queue cleared Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::stop Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::saveQueue Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Aug 28 13:37:25 volumio volumio[1144]: info: Preload queue cleared Aug 28 13:37:25 volumio volumio[1144]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/regi_ost/mp3_128 Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::saveQueue Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::play index 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::stop Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::play index undefined Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 28 13:37:25 volumio volumio[1144]: info: [1787917045496] ControllerWebradio::clearAddPlayTrack Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand stop took 7 milliseconds Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand clear took 1 milliseconds Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/regi_ost/mp3_128" Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Aug 28 13:37:25 volumio volumio[1144]: info: Ignoring MPD Status Update Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Aug 28 13:37:25 volumio volumio[1144]: info: Ignoring MPD Status Update Aug 28 13:37:25 volumio volumio[1144]: error: updateQueue error: null Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/regi_ost/mp3_128" Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 82ms Aug 28 13:37:25 volumio volumio[1144]: error: updateQueue error: null Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Aug 28 13:37:25 volumio volumio[1144]: info: Ignoring MPD Status Update Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 6ms Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/regi_ost/mp3_128" took 5 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Aug 28 13:37:25 volumio volumio[1144]: info: Ignoring MPD Status Update Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 3ms Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand play took 3 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 2ms Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces state update: player Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::getState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces state update: player Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::getState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces state update: player Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::getState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Aug 28 13:37:25 volumio volumio[1144]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand status took 17 milliseconds Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 13:37:25 volumio volumio[1144]: info: Aug 28 13:37:25 volumio volumio[1144]: ---------------------------- MPD announces state update: player Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::getState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Aug 28 13:37:25 volumio volumio[1144]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand status took 28 milliseconds Aug 28 13:37:25 volumio volumio[1144]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand status took 27 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand clearerror took 15 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/regi_ost/mp3_128","trackType":""} Aug 28 13:37:25 volumio volumio[1144]: verbose: CURRENT POSITION 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState stateService pause Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.724Z level=WARN msg="received unknown player status" component=volumio status="" Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.725Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" state=STATUS_STOPPED positionMs=142 volume=57 Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.725Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" id= title= Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 86ms Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand status took 81 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand clearerror took 70 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand clearerror took 69 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 59 milliseconds Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseState Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/regi_ost/mp3_128","trackType":""} Aug 28 13:37:25 volumio volumio[1144]: verbose: CURRENT POSITION 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState stateService pause Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/regi_ost/mp3_128","trackType":""} Aug 28 13:37:25 volumio volumio[1144]: verbose: CURRENT POSITION 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState stateService pause Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.783Z level=WARN msg="received unknown player status" component=volumio status="" Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.783Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" state=STATUS_STOPPED positionMs=268 volume=57 Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.785Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" id= title= Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.787Z level=WARN msg="received unknown player status" component=volumio status="" Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.788Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" state=STATUS_STOPPED positionMs=268 volume=57 Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.789Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" id= title= Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 153ms Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 152ms Aug 28 13:37:25 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 28 13:37:25 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Aug 28 13:37:25 volumio volumio[1144]: info: ControllerMpd::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Aug 28 13:37:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 28 13:37:25 volumio volumio[1144]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/regi_ost/mp3_128","trackType":""} Aug 28 13:37:25 volumio volumio[1144]: verbose: CURRENT POSITION 0 Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState stateService pause Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Aug 28 13:37:25 volumio volumio[1144]: info: CoreStateMachine::pushState Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Aug 28 13:37:25 volumio volumio[1144]: info: MRS: Pushing multiroomSync output Aug 28 13:37:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.834Z level=WARN msg="received unknown player status" component=volumio status="" Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" state=STATUS_STOPPED positionMs=268 volume=57 Aug 28 13:37:25 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:25.834Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" id= title= Aug 28 13:37:25 volumio volumio[1144]: info: ------------------------------ 164ms Aug 28 13:37:25 volumio volumio[1144]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.TO7BHhMoZRMpPhzRViKkNSSkTAm2.8f8a5a479299e8c5402efdbaf677045b.state.status' Aug 28 13:37:25 volumio volumio[1144]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.TO7BHhMoZRMpPhzRViKkNSSkTAm2.8f8a5a479299e8c5402efdbaf677045b.state.status' Aug 28 13:37:27 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:27.104Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.891Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-280.936617ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.904Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.923Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=18.269895ms Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.924Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=http://pushupdates.volumio.org duration=17.587031ms Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.931Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=24.870365ms Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.952Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=44.495052ms Aug 28 13:37:28 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:28.987Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://google.com duration=81.942187ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.019Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://www.googleapis.com duration=113.602708ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.033Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=126.918333ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.038Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://securetoken.googleapis.com duration=131.138177ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.049Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://database.volumio.cloud duration=141.520312ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.055Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://functions.volumio.cloud duration=148.13224ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.062Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=https://functions.volumio.cloud duration=155.202031ms Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.250Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=http://cddb.volumio.org duration=344.110781ms Aug 28 13:37:29 volumio sudo[3920]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 13:37:29 volumio sudo[3920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:29 volumio sudo[3920]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:29 volumio sudo[3922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 13:37:29 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:29 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:29 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:29.369Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" latency=-283.245367ms timeout=10s endpoint=http://plugins.volumio.org duration=461.580729ms Aug 28 13:37:29 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.76 UA: Mozilla/5.0 (Linux; Android 16; motorola edge 60 pro Build/W1VVS36H.7-108-8-6; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 28 13:37:29 volumio sudo[3926]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 13:37:29 volumio sudo[3926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:29 volumio sudo[3928]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 13:37:29 volumio sudo[3928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:37:29 volumio sudo[3926]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:29 volumio sudo[3928]: pam_unix(sudo:session): session closed for user root Aug 28 13:37:29 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.76 UA: Mozilla/5.0 (Linux; Android 16; motorola edge 60 pro Build/W1VVS36H.7-108-8-6; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 28 13:37:29 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 13:37:30 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 13:37:31 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:31.004Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetQueue Aug 28 13:37:32 volumio volumio[1144]: info: CoreStateMachine::getQueue Aug 28 13:37:32 volumio volumio[1144]: info: CorePlayQueue::getQueue Aug 28 13:37:32 volumio volumio[1144]: info: Listing playlists Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 13:37:32 volumio volumio[1144]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 13:37:32 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:32 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 13:37:32 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:32 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 13:37:32 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:32 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:32 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:37:34 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:34.309Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 28 13:37:34 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:34.309Z level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x16a2db0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED error="peer is gone" Aug 28 13:37:34 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:34.310Z level=INFO msg="emitting device setup status changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.76:36750 @ 0x16a2db0" setupComplete=true Aug 28 13:37:34 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:34.310Z level=ERROR msg="failed to send event" component=server dst=" @ 0x16a2db0" event=SERVER_EVENT_TYPE_DEVICE_SETUP_STATUS_CHANGED error="no addresses to write to" Aug 28 13:37:34 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:34.310Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01,192.168.1.76:36750 @ 0x16a2db0" latency=-281.945381ms timeout=20s error="failed to get output devices: could not get extended output devices: context deadline exceeded" Aug 28 13:37:34 volumio volumio5-onboarding[1698]: time=2026-08-28T11:37:34.310Z level=ERROR msg="failed to send response" component=server dst=" @ 0x16a2db0" id=1984143688 status=STATUS_UNKNOWN error="no addresses to write to" Aug 28 13:37:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 13:37:37 volumio volumio[1144]: info: Getting Alsa Cards List without I2S DAC Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 28 13:37:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 13:37:39 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 13:37:47 volumio volumio[1144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 13:37:47 volumio volumio[1144]: info: Received Get System Version Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 13:37:47 volumio volumio[1144]: info: Received Get System Info Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 13:37:47 volumio volumio[1144]: info: Discovery: Getting this device information Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:37:47 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 13:38:02 volumio volumio[1144]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 28 13:38:02 volumio volumio[1144]: info: Preparing to save Alsa Options, stopping services first Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:02 volumio volumio[1144]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi","label":"HiFiBerry Digi"}} Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:02 volumio volumio[1144]: info: Enabling I2S DAC: HiFiBerry Digi Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:02 volumio sudo[3990]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 28 13:38:02 volumio sudo[3990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:02 volumio sudo[3990]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:02 volumio sudo[3994]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r allo-boss2-dac-audio Aug 28 13:38:02 volumio sudo[3994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:02 volumio kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /soc/i2s@7e203000/port Aug 28 13:38:02 volumio sudo[3994]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:02 volumio volumio[1144]: info: Removed overlay allo-boss2-dac-audio Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:02 volumio sudo[4010]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-digi Aug 28 13:38:02 volumio sudo[4010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:02 volumio volumio[1144]: warn: Unable to locate the device HDMI Out a reboot may be required. Aug 28 13:38:02 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Aug 28 13:38:02 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Aug 28 13:38:02 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Aug 28 13:38:02 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Aug 28 13:38:02 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Aug 28 13:38:02 volumio sudo[4010]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:02 volumio volumio[1144]: info: Device 2 does not have any Mixer Control Available Aug 28 13:38:02 volumio volumio[1144]: info: QobuzConnect: setDeactiveState invoked Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:02 volumio vtcs[3341]: [2026-08-28 13:38:02.916] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 28 13:38:02 volumio vtcs[3341]: [2026-08-28 13:38:02.918] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 28 13:38:02 volumio kernel: wm8804 1-003b: Failed to read device ID: -121 Aug 28 13:38:02 volumio kernel: wm8804 1-003b: probe with driver wm8804 failed with error -121 Aug 28 13:38:02 volumio sudo[4033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 13:38:02 volumio sudo[4033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 13:38:02 volumio volumio[1144]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 28 13:38:02 volumio volumio[1144]: info: Disabling external Volume Control Aug 28 13:38:02 volumio volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 28 13:38:03 volumio volumio[1144]: info: CoreStateMachine::pushState Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Aug 28 13:38:03 volumio volumio[1144]: info: MRS: Pushing multiroomSync output update for this device Aug 28 13:38:03 volumio volumio[1144]: info: MRS: Pushing multiroomSync output Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:03 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 28 13:38:03 volumio systemd[1]: vtcs.service: Killing process 3348 (vtcs) with signal SIGKILL. Aug 28 13:38:03 volumio volumio5-onboarding[1698]: time=2026-08-28T11:38:03.037Z level=WARN msg="received unknown player status" component=volumio status="" Aug 28 13:38:03 volumio volumio5-onboarding[1698]: time=2026-08-28T11:38:03.037Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" state=STATUS_STOPPED positionMs=37202 volume=100 Aug 28 13:38:03 volumio volumio5-onboarding[1698]: time=2026-08-28T11:38:03.038Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.76:43974,00:00:00:00:00:00%02 @ 0x17a14d0" id= title= Aug 28 13:38:03 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 13:38:03 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 13:38:03 volumio sudo[4033]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio volumio[1144]: info: Preparing to generate the ALSA configuration file Aug 28 13:38:03 volumio sudo[4041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 13:38:03 volumio sudo[4041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio volumio[1144]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 28 13:38:03 volumio volumio[1144]: info: Reading ALSA contributions from plugins. Aug 28 13:38:03 volumio sudo[4048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 28 13:38:03 volumio sudo[4048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio volumio[1144]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Aug 28 13:38:03 volumio volumio[1144]: info: I2S Param [object Object] successfully enabled Aug 28 13:38:03 volumio sudo[4048]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 13:38:03 volumio volumio[1144]: info: Getting Alsa Cards List without I2S DAC Aug 28 13:38:03 volumio sudo[4041]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio sudo[4053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 28 13:38:03 volumio sudo[4053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:03 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 28 13:38:03 volumio qobuz-connect[3322]: 20260828 13:38:03.295 [3322.3322] INFO SampleApp: Stopping Local configuration server Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 13:38:03 volumio volumio[1144]: info: Asound.conf file written Aug 28 13:38:03 volumio sudo[4067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 28 13:38:03 volumio sudo[4067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio sudo[4067]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio volumio[1144]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 28 13:38:03 volumio volumio[1144]: No state is present for card b1 Aug 28 13:38:03 volumio volumio[1144]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 28 13:38:03 volumio volumio[1144]: /usr/share/alsa/init/bcm2835:14: control element not found Aug 28 13:38:03 volumio volumio[1144]: No state is present for card b1 Aug 28 13:38:03 volumio volumio[1144]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 28 13:38:03 volumio volumio[1144]: No state is present for card Headphones Aug 28 13:38:03 volumio volumio[1144]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 28 13:38:03 volumio volumio[1144]: /usr/share/alsa/init/bcm2835:20: control element not found Aug 28 13:38:03 volumio volumio[1144]: No state is present for card Headphones Aug 28 13:38:03 volumio volumio[1144]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 28 13:38:03 volumio volumio[1144]: No state is present for card vc4hdmi Aug 28 13:38:03 volumio volumio[1144]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 28 13:38:03 volumio volumio[1144]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 28 13:38:03 volumio volumio[1144]: Hardware is initialized using a generic method Aug 28 13:38:03 volumio volumio[1144]: No state is present for card vc4hdmi Aug 28 13:38:03 volumio volumio[1144]: info: Output device has changed, restarting MPD Aug 28 13:38:03 volumio volumio[1144]: info: Output device has changed, restarting Shairport Sync Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:03 volumio sudo[4073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 13:38:03 volumio sudo[4073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio sudo[4075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 13:38:03 volumio sudo[4075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio sudo[4073]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio volumio[1144]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 13:38:03 volumio volumio[1144]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: QobuzConnect: setDeactiveState invoked Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:03 volumio volumio[1144]: info: Output device has changed, restarting MPD Aug 28 13:38:03 volumio qobuz-connect[3322]: 20260828 13:38:03.538 [3322.3322] INFO SampleApp: shat down connection on UNIX socket Aug 28 13:38:03 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 28 13:38:03 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 28 13:38:03 volumio volumio[1144]: info: Output device has changed, restarting Shairport Sync Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 28 13:38:03 volumio sudo[4085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 13:38:03 volumio sudo[4085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio sudo[4087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 13:38:03 volumio sudo[4087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio sudo[4087]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio sudo[4089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 13:38:03 volumio sudo[4089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio volumio[1144]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 13:38:03 volumio volumio[1144]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: QobuzConnect: setDeactiveState invoked Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:03 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 28 13:38:03 volumio sudo[4053]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 28 13:38:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 13:38:03 volumio systemd[1]: mpd.service: Consumed 5.030s CPU time. Aug 28 13:38:03 volumio sudo[4102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 13:38:03 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 28 13:38:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 13:38:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 13:38:03 volumio sudo[4103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 13:38:03 volumio sudo[4103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 13:38:03 volumio sudo[4105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 13:38:03 volumio sudo[4105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 13:38:03 volumio sudo[4116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 28 13:38:03 volumio sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio sudo[4085]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 28 13:38:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 13:38:03 volumio volumio[1144]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 28 13:38:03 volumio volumio[1144]: info: MPD Permissions set Aug 28 13:38:03 volumio volumio[1144]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 28 13:38:03 volumio volumio[1144]: info: MPD Permissions set Aug 28 13:38:03 volumio volumio[1144]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 28 13:38:03 volumio volumio[1144]: info: QobuzConnect: setDeactiveState invoked Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:03 volumio sudo[4123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 28 13:38:03 volumio sudo[4123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:03 volumio volumio[1144]: info: Executing endpoint qc_getconfig Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 28 13:38:03 volumio qobuz-connect[4094]: 20260828 13:38:03.956 [4094.4094] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 28 13:38:03 volumio sudo[4123]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 13:38:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 13:38:03 volumio qobuz-connect[4094]: 20260828 13:38:03.972 [4094.4094] INFO VolumeManager: [0x1e18e88]: Setting new playback volume: 75 Aug 28 13:38:03 volumio qobuz-connect[4094]: 20260828 13:38:03.972 [4094.4094] INFO VolumeManager: [0x1e18e88]: Setting new mute state: 0 Aug 28 13:38:03 volumio qobuz-connect[4094]: 20260828 13:38:03.972 [4094.4094] INFO AudioStreamManager: [0x1e18be0]: Setting new audio download buffer size: 1048576 Aug 28 13:38:03 volumio qobuz-connect[4094]: 20260828 13:38:03.972 [4094.4094] INFO QobuzConnect: [0x1e19750]: Client initialized! Aug 28 13:38:03 volumio qobuz-connect[4094]: 20260828 13:38:03.972 [4094.4094] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Aug 28 13:38:03 volumio sudo[4103]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio qobuz-connect[4094]: 20260828 13:38:04.005 [4094.4094] INFO LocalConfigManager: [0x1e18908]: Starting Local Configuration server Aug 28 13:38:04 volumio qobuz-connect[4094]: 20260828 13:38:04.006 [4094.4094] INFO SampleApp: Starting Local configuration server Aug 28 13:38:04 volumio qobuz-connect[4094]: 20260828 13:38:04.007 [4094.4094] INFO SampleApp: Connected to UNIX socket client 0x1e03818 Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 28 13:38:04 volumio volumio[1144]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio sudo[4129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 28 13:38:04 volumio sudo[4129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 13:38:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 13:38:04 volumio volumio[1144]: info: Starting Shairport Sync Aug 28 13:38:04 volumio sudo[4105]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio volumio[1144]: info: Starting Shairport Sync Aug 28 13:38:04 volumio qobuz-connect[4094]: 20260828 13:38:04.108 [4094.4094] INFO SampleApp: Playback volume changed: 75 Aug 28 13:38:04 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 28 13:38:04 volumio sudo[4147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 13:38:04 volumio sudo[4147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:04 volumio sudo[4116]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio sudo[4150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 13:38:04 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio sudo[4150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:04 volumio sudo[4154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 28 13:38:04 volumio sudo[4154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:04 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 28 13:38:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 13:38:04 volumio qobuz-connect[4094]: 20260828 13:38:04.235 [4094.4094] INFO SampleApp: Stopping Local configuration server Aug 28 13:38:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 13:38:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 13:38:04 volumio systemd[1]: shairport-sync.service: Consumed 2.543s CPU time. Aug 28 13:38:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 13:38:04 volumio sudo[4145]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 13:38:04 volumio sudo[4145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 13:38:04 volumio sudo[4150]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio sudo[4145]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio volumio[1144]: info: Shairport-Sync Started Aug 28 13:38:04 volumio sudo[4147]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:04 volumio volumio[1144]: info: Shairport-Sync Started Aug 28 13:38:04 volumio volumio[1144]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 28 13:38:04 volumio volumio[1144]: info: PLUGIN onReboot : networkfs Aug 28 13:38:04 volumio sudo[4177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Aug 28 13:38:04 volumio sudo[4177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 13:38:05 volumio systemd-logind[750]: The system will reboot now! Aug 28 13:38:05 volumio systemd-logind[750]: System is rebooting. Aug 28 13:38:05 volumio sudo[4129]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:05 volumio sudo[4089]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:05 volumio sudo[4075]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:05 volumio sudo[4154]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:05 volumio volumio[1144]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Aug 28 13:38:05 volumio volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 13:38:05 volumio sudo[4177]: pam_unix(sudo:session): session closed for user root Aug 28 13:38:05 volumio volumio[1144]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { Aug 28 13:38:05 volumio volumio[1144]: code: 'ERR_UNHANDLED_REJECTION' Aug 28 13:38:05 volumio volumio[1144]: } Aug 28 13:38:05 volumio volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 13:38:05 volumio systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Aug 28 13:38:05 volumio systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Aug 28 13:38:05 volumio systemd[1]: Stopped target graphical.target - Graphical Interface. Aug 28 13:38:05 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 28 13:38:05 volumio systemd[1]: Stopped target rpc_pipefs.target. Aug 28 13:38:05 volumio systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Aug 28 13:38:05 volumio systemd[1]: Stopped target timers.target - Timer Units. Aug 28 13:38:05 volumio systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Aug 28 13:38:05 volumio systemd[1]: apt-daily.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Aug 28 13:38:05 volumio systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Aug 28 13:38:05 volumio systemd[1]: e2scrub_all.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Aug 28 13:38:05 volumio systemd[1]: fstrim.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Aug 28 13:38:05 volumio systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Aug 28 13:38:05 volumio systemd[1]: setdatetime-helper.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Aug 28 13:38:05 volumio systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 28 13:38:05 volumio systemd[1]: systemd-rfkill.socket: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 28 13:38:05 volumio systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Aug 28 13:38:05 volumio bluealsa[1005]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Aug 28 13:38:05 volumio systemd[1]: Stopping bluealsa.service - BlueALSA service... Aug 28 13:38:05 volumio bluetoothd[930]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 28 13:38:05 volumio 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). Aug 28 13:38:05 volumio sshd[1533]: Received signal 15; terminating. Aug 28 13:38:05 volumio systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... Aug 28 13:38:05 volumio autossh[3743]: received signal to exit (15) Aug 28 13:38:05 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Aug 28 13:38:05 volumio systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Aug 28 13:38:05 volumio systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Aug 28 13:38:05 volumio systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Aug 28 13:38:05 volumio bluetoothd[930]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 28 13:38:05 volumio bluetoothd[930]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 28 13:38:05 volumio volumiobt[4194]: INFO [BTSTART] Disconnecting all Bluetooth devices... Aug 28 13:38:05 volumio systemd[1]: bluealsa.service: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped bluealsa.service - BlueALSA service. Aug 28 13:38:05 volumio bluetoothd[930]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 28 13:38:05 volumio systemd[1]: ssh.service: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. Aug 28 13:38:05 volumio systemd[1]: volumio5-onboarding.service: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 13:38:05 volumio systemd[1]: volumio5-onboarding.service: Consumed 5.528s CPU time. Aug 28 13:38:05 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 28 13:38:05 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 13:38:05 volumio systemd[1]: mpd.service: Consumed 1.151s CPU time. Aug 28 13:38:05 volumio systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Aug 28 13:38:05 volumio systemd[1]: systemd-random-seed.service: Deactivated successfully. Aug 28 13:38:05 volumio systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Aug 28 13:38:05 volumio bluetoothd[930]: Adv Monitor app :1.69 disconnected from D-Bus Aug 28 13:38:05 volumio volumiobt[4202]: Attempting to disconnect from 5D:65:62:52:9B:C1 Aug 28 13:38:05 volumio volumiobt[4202]: [NEW] Media /org/bluez/hci0 Aug 28 13:38:05 volumio volumiobt[4202]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 28 13:38:05 volumio volumiobt[4202]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 28 13:38:05 volumio volumiobt[4202]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 28 13:38:05 volumio bluetoothd[930]: Path / reserved for Adv Monitor app :1.70 Aug 28 13:38:05 volumio volumiobt[4202]: AdvertisementMonitor path registered Aug 28 13:38:06 volumio qobuz-connect[4094]: 20260828 13:38:06.015 [4094.4094] INFO SampleApp: shat down connection on UNIX socket Aug 28 13:38:06 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 28 13:38:06 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 28 13:38:06 volumio sudo[4205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 13:37' Aug 28 13:38:06 volumio sudo[4205]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"