Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stPlaybackTimer Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::updateTrackBlock Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrackBlock Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::serviceStop Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::serviceStop Oct 14 10:14:08 tmba volumio[1234]: info: ControllerMpd::stop Oct 14 10:14:08 tmba volumio[1234]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: Airplay started streaming, receiving metadatas Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba shairport-sync[3725]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 10:14:08 tmba shairport-sync[3725]: Dload Upload Total Spent Left Speed Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - Volumio is not playing Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - Clipped samples monitor stopped Oct 14 10:14:08 tmba volumio[1234]: info: sendMpdCommand stop took 27 milliseconds Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/6909.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"In A Little While","title":"In A Little While","artist":"U2","album":"All That You Can't Leave Behind","streaming":true,"disableUiControls":true,"duration":67,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 14 10:14:08 tmba volumio[1234]: verbose: CURRENT POSITION 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::syncState stateService stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::syncState currentStatus stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:08 tmba volumio[1234]: info: No code Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:08 tmba shairport-sync[3725]: [79B blob data] Oct 14 10:14:08 tmba shairport-sync[3723]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 10:14:08 tmba shairport-sync[3723]: Dload Upload Total Spent Left Speed Oct 14 10:14:08 tmba volumio[1234]: info: Updating volume from AirPlay: -21.4; 29% Oct 14 10:14:08 tmba volumio[1234]: info: VolumeController::SetAlsaVolume29 Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:08 tmba volumio[1234]: info: Oct 14 10:14:08 tmba volumio[1234]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 14 10:14:08 tmba volumio[1234]: info: Airplay playback start Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::volumioStop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::stop Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 10:14:08 tmba shairport-sync[3723]: [79B blob data] Oct 14 10:14:08 tmba shairport-sync[3725]: [79B blob data] Oct 14 10:14:08 tmba volumio[1234]: info: touch_display: Setting screensaver timeout to 1800 seconds. Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:08 tmba volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 14 10:14:08 tmba volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received airplay_emulation Oct 14 10:14:08 tmba volumio[1234]: info: Oct 14 10:14:08 tmba volumio[1234]: ---------------------------- Client requests AirplayActive Oct 14 10:14:08 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Oct 14 10:14:08 tmba shairport-sync[3725]: {"time":1791965648220,"response":"startAirplayPlayback Success"} Oct 14 10:14:08 tmba shairport-sync[3723]: [79B blob data] Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 10:14:08 tmba volumio[1234]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: FusionDsp - Volumio is playing Oct 14 10:14:09 tmba volumio[1234]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:09 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:09 tmba volumio[1234]: info: FusionDsp - Clipping Monitor started Oct 14 10:14:09 tmba volumio[1234]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 14 10:14:10 tmba systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 37. Oct 14 10:14:10 tmba systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:10 tmba systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:10 tmba upmpdcli[3753]: Could not open config: /tmp/upmpdcli.conf Oct 14 10:14:10 tmba systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 10:14:10 tmba systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 10:14:14 tmba volumio[1234]: info: Updating volume from AirPlay: -17.28; 42% Oct 14 10:14:14 tmba volumio[1234]: info: VolumeController::SetAlsaVolume42 Oct 14 10:14:14 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:14 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:14 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:14 tmba volumio[1234]: info: Updating volume from AirPlay: -15.24; 49% Oct 14 10:14:14 tmba volumio[1234]: info: VolumeController::SetAlsaVolume49 Oct 14 10:14:14 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:14 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:14 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:14 tmba volumio[1234]: info: Updating volume from AirPlay: -13.85; 54% Oct 14 10:14:14 tmba volumio[1234]: info: VolumeController::SetAlsaVolume54 Oct 14 10:14:14 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:14 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:14 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:15 tmba volumio[1234]: info: Updating volume from AirPlay: -14.67; 51% Oct 14 10:14:15 tmba volumio[1234]: info: VolumeController::SetAlsaVolume51 Oct 14 10:14:16 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:16 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:16 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:16 tmba volumio[1234]: info: Updating volume from AirPlay: -16.92; 44% Oct 14 10:14:16 tmba volumio[1234]: info: VolumeController::SetAlsaVolume44 Oct 14 10:14:16 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:16 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:16 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:16 tmba volumio[1234]: info: Updating volume from AirPlay: -18.25; 39% Oct 14 10:14:16 tmba volumio[1234]: info: VolumeController::SetAlsaVolume39 Oct 14 10:14:16 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:16 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:16 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:25 tmba systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 38. Oct 14 10:14:25 tmba systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:26 tmba systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:26 tmba upmpdcli[3796]: Could not open config: /tmp/upmpdcli.conf Oct 14 10:14:26 tmba systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 10:14:26 tmba systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 10:14:29 tmba volumio[1234]: info: CALLMETHOD: audio_interface fusiondsp configureVeryAdvSet [object Object] Oct 14 10:14:29 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , configureVeryAdvSet Oct 14 10:14:29 tmba volumio[1234]: info: FusionDsp - Chunksise set to 256 Oct 14 10:14:29 tmba volumio[1234]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 10:14:29 tmba volumio[1234]: info: FusionDsp - Reload response: {"Reload":{"result":"Ok"}} Oct 14 10:14:29 tmba volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 10:14:29 tmba volumio[1234]: info: CoreCommandRouter::Close All Modals sent Oct 14 10:14:41 tmba systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 39. Oct 14 10:14:41 tmba systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:41 tmba systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:41 tmba upmpdcli[3817]: Could not open config: /tmp/upmpdcli.conf Oct 14 10:14:41 tmba systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 10:14:41 tmba systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::servicePushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreStateMachine::pushState Oct 14 10:14:48 tmba volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 14 10:14:48 tmba volumio[1234]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 14 10:14:49 tmba volumio[1234]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 14 10:14:49 tmba volumio[1234]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 10:14:49 tmba volumio[1234]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 10:14:49 tmba volumio[1234]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 10:14:49 tmba volumio[1234]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 10:14:49 tmba volumio[1234]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 14 10:14:49 tmba volumio[1234]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 10:14:51 tmba volumio[1234]: info: FusionDsp - Clipping Monitor started Oct 14 10:14:56 tmba systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 40. Oct 14 10:14:56 tmba systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:56 tmba systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:14:56 tmba upmpdcli[3868]: Could not open config: /tmp/upmpdcli.conf Oct 14 10:14:56 tmba systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 10:14:56 tmba systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 10:15:11 tmba systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 41. Oct 14 10:15:11 tmba systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:15:11 tmba systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 10:15:11 tmba upmpdcli[3884]: Could not open config: /tmp/upmpdcli.conf Oct 14 10:15:11 tmba systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 10:15:11 tmba systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 10:15:13 tmba shairport-sync[3723]: {"time":1791965648280,"response":"airplayActive Success"} Oct 14 10:15:13 tmba shairport-sync[3563]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:101:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook failed to run command echo '44100,S32_LE,2,32' >/tmp/fusiondsp_stream_params.log Oct 14 10:15:13 tmba volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 10:15:13 tmba volumio[1234]: RangeError [ERR_OUT_OF_RANGE]: The value of "byteLength" is out of range. It must be >= 1 and <= 6. Received 8 Oct 14 10:15:13 tmba volumio[1234]: at new NodeError (node:internal/errors:405:5) Oct 14 10:15:13 tmba volumio[1234]: at boundsError (node:internal/buffer:88:9) Oct 14 10:15:13 tmba volumio[1234]: at Buffer.readUIntBE (node:internal/buffer:275:3) Oct 14 10:15:13 tmba volumio[1234]: at Object.number (/volumio/node_modules/daap-parser/daap-parser.js:33:15) Oct 14 10:15:13 tmba volumio[1234]: at Object.tag [as parseTag] (/volumio/node_modules/daap-parser/daap-parser.js:85:37) Oct 14 10:15:13 tmba volumio[1234]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:89:38) Oct 14 10:15:13 tmba volumio[1234]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Oct 14 10:15:13 tmba volumio[1234]: at Socket.emit (node:events:514:28) Oct 14 10:15:13 tmba volumio[1234]: at UDP.onMessage [as onmessage] (node:dgram:941:8) { Oct 14 10:15:13 tmba volumio[1234]: code: 'ERR_OUT_OF_RANGE' Oct 14 10:15:13 tmba volumio[1234]: } Oct 14 10:15:13 tmba volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 10:15:13 tmba sudo[3900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 10:14' Oct 14 10:15:13 tmba sudo[3900]: 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="ebd772339e193fb4b3a98c68e63a0009e72d6f27" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 14 07:39:23 UTC 2026" VOLUMIO_VERSION="4.204" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="915e71dafc26fd850574d238b4f947d5"