Oct 12 11:23:16 volumio pulseaudio[20386]: W: [pulseaudio] module-loopback.c: Too many underruns, increasing latency to 377.45 ms Oct 12 11:23:40 volumio pulseaudio[20386]: W: [pulseaudio] module-loopback.c: Too many underruns, increasing latency to 382.45 ms Oct 12 11:23:43 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:43 volumio volumio[21959]: info: VolumeController::SetAlsaVolume93 Oct 12 11:23:43 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 93 Oct 12 11:23:43 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:43 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:43 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:43 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:43 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:43 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:43 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:43 volumio volumio[21959]: info: VolumeController::SetAlsaVolume87 Oct 12 11:23:43 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 87 Oct 12 11:23:43 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:43 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:43 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:43 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:43 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:43 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:44 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:44 volumio volumio[21959]: info: VolumeController::SetAlsaVolume81 Oct 12 11:23:44 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 81 Oct 12 11:23:44 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:44 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:44 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:44 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:44 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:44 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:44 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:44 volumio volumio[21959]: info: VolumeController::SetAlsaVolume74 Oct 12 11:23:44 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 74 Oct 12 11:23:44 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:44 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:44 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:45 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:45 volumio volumio[21959]: info: VolumeController::SetAlsaVolume68 Oct 12 11:23:45 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 68 Oct 12 11:23:45 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:45 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:45 volumio volumio[21959]: info: VolumeController::SetAlsaVolume62 Oct 12 11:23:45 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 62 Oct 12 11:23:45 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:45 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:45 volumio volumio[21959]: info: VolumeController::SetAlsaVolume55 Oct 12 11:23:45 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 55 Oct 12 11:23:45 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:45 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:45 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:46 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:46 volumio volumio[21959]: info: VolumeController::SetAlsaVolume50 Oct 12 11:23:46 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 50 Oct 12 11:23:46 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:46 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:46 volumio volumio[21959]: info: VolumeController::SetAlsaVolume44 Oct 12 11:23:46 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 44 Oct 12 11:23:46 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:46 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:46 volumio volumio[21959]: info: VolumeController::SetAlsaVolume37 Oct 12 11:23:46 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 37 Oct 12 11:23:46 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:46 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:46 volumio volumio[21959]: info: VolumeController::SetAlsaVolume31 Oct 12 11:23:46 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 31 Oct 12 11:23:46 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:46 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:46 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:47 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:47 volumio volumio[21959]: info: VolumeController::SetAlsaVolume25 Oct 12 11:23:47 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 25 Oct 12 11:23:47 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:47 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:47 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:47 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:47 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:47 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:48 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:48 volumio volumio[21959]: info: VolumeController::SetAlsaVolume31 Oct 12 11:23:48 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 31 Oct 12 11:23:48 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:48 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:48 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:48 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:48 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:48 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:49 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:49 volumio volumio[21959]: info: VolumeController::SetAlsaVolume37 Oct 12 11:23:49 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 37 Oct 12 11:23:49 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:49 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:49 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:49 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:49 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:49 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:49 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:49 volumio volumio[21959]: info: VolumeController::SetAlsaVolume44 Oct 12 11:23:49 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 44 Oct 12 11:23:49 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:49 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:49 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:49 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:49 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:49 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:50 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:50 volumio volumio[21959]: info: VolumeController::SetAlsaVolume50 Oct 12 11:23:50 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 50 Oct 12 11:23:50 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:50 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:50 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:50 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:50 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:50 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:23:50 volumio bluetoothd[24450]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 Oct 12 11:23:50 volumio volumio[21959]: info: VolumeController::SetAlsaVolume55 Oct 12 11:23:50 volumio volumio[21959]: BT PLUGIN MESSAGE: Volume set to 55 Oct 12 11:23:50 volumio volumio[21959]: info: CoreStateMachine::pushState Oct 12 11:23:50 volumio volumio[21959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:23:50 volumio volumio[21959]: info: CoreCommandRouter::volumioPushState Oct 12 11:23:50 volumio volumio[21959]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:23:50 volumio volumio[21959]: info: MRS: Pushing multiroomSync output Oct 12 11:23:50 volumio volumio[21959]: info: CoreCommandRouter::volumioGetState Oct 12 11:24:00 volumio volumio[21959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 11:24:00 volumio volumio[21959]: Error: connect ETIMEDOUT 108.160.172.204:443 Oct 12 11:24:00 volumio volumio[21959]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 12 11:24:00 volumio volumio[21959]: errno: -110, Oct 12 11:24:00 volumio volumio[21959]: code: 'ETIMEDOUT', Oct 12 11:24:00 volumio volumio[21959]: syscall: 'connect', Oct 12 11:24:00 volumio volumio[21959]: address: '108.160.172.204', Oct 12 11:24:00 volumio volumio[21959]: port: 443 Oct 12 11:24:00 volumio volumio[21959]: } Oct 12 11:24:00 volumio volumio[21959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 11:24:01 volumio sudo[26157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 11:23' Oct 12 11:24:01 volumio sudo[26157]: 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"