-- Logs begin at Sat 2025-05-03 08:31:07 CEST, end at Thu 2025-05-08 18:14:31 CEST. -- May 08 18:13:19 raspi1 volumio[1250]: info: Airplay started streaming, receiving metadatas May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioStop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::updateTrackBlock May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrackBlock May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::stPlaybackTimer May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:19 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:19 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::serviceStop May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::serviceStop May 08 18:13:19 raspi1 volumio[1250]: info: [1746720799358] ControllerTidal::stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 18:13:19 raspi1 volumio[1250]: info: ControllerMpd::stop May 08 18:13:19 raspi1 volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: Airplay started streaming, receiving metadatas May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioStop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: Airplay started streaming, receiving metadatas May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioStop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: Airplay started streaming, receiving metadatas May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioStop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 shairport-sync[2141]: % Total % Received % Xferd Average Speed Time Time Time Current May 08 18:13:19 raspi1 shairport-sync[2141]: Dload Upload Total Spent Left Speed May 08 18:13:19 raspi1 volumio[1250]: info: May 08 18:13:19 raspi1 volumio[1250]: ---------------------------- MPD announces state update: player May 08 18:13:19 raspi1 volumio[1250]: info: sendMpdCommand stop took 23 milliseconds May 08 18:13:19 raspi1 volumio[1250]: info: ControllerMpd::getState May 08 18:13:19 raspi1 volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:19 raspi1 volumio[1250]: info: sendMpdCommand status took 1 milliseconds May 08 18:13:19 raspi1 volumio[1250]: verbose: ControllerMpd::parseState May 08 18:13:19 raspi1 volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:13:19 raspi1 volumio[1250]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 18:13:19 raspi1 volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 08 18:13:19 raspi1 volumio[1250]: info: ControllerMpd::pushState May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:19 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:19 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 08 18:13:19 raspi1 volumio[1250]: info: ------------------------------ 11ms May 08 18:13:19 raspi1 volumio[1250]: info: May 08 18:13:19 raspi1 volumio[1250]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 08 18:13:19 raspi1 volumio[1250]: info: Airplay playback start May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioStop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::stop May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:13:19 raspi1 shairport-sync[2141]: [158B blob data] May 08 18:13:19 raspi1 volumio[1250]: info: Updating volume from AirPlay: -20; 33% May 08 18:13:19 raspi1 volumio[1250]: info: VolumeController::SetAlsaVolume33 May 08 18:13:19 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:19 raspi1 volumio[1250]: info: CorePlayQueue::getTrack 7 May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:19 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:19 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:19 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:19 raspi1 shairport-sync[2141]: {"time":1746720799394,"response":"startAirplayPlayback Success"}ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 May 08 18:13:19 raspi1 shairport-sync[2141]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 May 08 18:13:19 raspi1 shairport-sync[2141]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 May 08 18:13:19 raspi1 shairport-sync[2141]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:20 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:20 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:20 raspi1 volumio[1250]: info: touch_display: Setting screensaver timeout to 0 seconds. May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::servicePushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:21 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:21 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:23 raspi1 volumio[1250]: info: Executing endpoint metavolumio May 08 18:13:23 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 18:13:23 raspi1 volumio[1250]: info: Executing endpoint metavolumio May 08 18:13:23 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 18:13:23 raspi1 volumio[1250]: info: Executing endpoint metavolumio May 08 18:13:23 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 18:13:25 raspi1 volumio[1250]: info: Updating volume from AirPlay: -18.12; 40% May 08 18:13:25 raspi1 volumio[1250]: info: VolumeController::SetAlsaVolume40 May 08 18:13:25 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:13:25 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:25 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:13:25 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:13:25 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:13:30 raspi1 volumio[1250]: error: Failed request for metavolumio API May 08 18:13:30 raspi1 volumio[1250]: error: Failed request for metavolumio API May 08 18:13:30 raspi1 volumio[1250]: error: Failed request for metavolumio API May 08 18:13:47 raspi1 volumio[1250]: error: error May 08 18:14:30 raspi1 volumio[1250]: info: Updating volume from AirPlay: 0; 100% May 08 18:14:30 raspi1 volumio[1250]: info: VolumeController::SetAlsaVolume100 May 08 18:14:30 raspi1 volumio[1250]: info: CoreStateMachine::pushState May 08 18:14:30 raspi1 volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:14:30 raspi1 volumio[1250]: info: CoreCommandRouter::volumioPushState May 08 18:14:30 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output update for this device May 08 18:14:30 raspi1 volumio[1250]: info: MRS: Pushing multiroomSync output May 08 18:14:30 raspi1 volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:14:30 raspi1 volumio[1250]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net May 08 18:14:30 raspi1 volumio[1250]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 08 18:14:30 raspi1 volumio[1250]: errno: -3007, May 08 18:14:30 raspi1 volumio[1250]: code: 'ENOTFOUND', May 08 18:14:30 raspi1 volumio[1250]: syscall: 'getaddrinfo', May 08 18:14:30 raspi1 volumio[1250]: hostname: 'lastfm.freetls.fastly.net' May 08 18:14:30 raspi1 volumio[1250]: } May 08 18:14:30 raspi1 volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:14:31 raspi1 sudo[31459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 18:13 May 08 18:14:31 raspi1 sudo[31459]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"