-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Sat 2026-05-30 09:56:18 EDT. -- May 30 09:55:30 volumio volumio[1008]: info: Airplay started streaming, receiving metadatas May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioStop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::updateTrackBlock May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrackBlock May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::stPlaybackTimer May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::serviceStop May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::serviceStop May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::stop May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: Airplay started streaming, receiving metadatas May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioStop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: Airplay started streaming, receiving metadatas May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioStop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: May 30 09:55:30 volumio volumio[1008]: ---------------------------- MPD announces state update: player May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand stop took 16 milliseconds May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::getState May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status May 30 09:55:30 volumio volumio[1008]: info: May 30 09:55:30 volumio volumio[1008]: ---------------------------- MPD announces state update: player May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::getState May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status May 30 09:55:30 volumio volumio[1008]: info: May 30 09:55:30 volumio volumio[1008]: ---------------------------- MPD announces state update: player May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::getState May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand status took 5 milliseconds May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::parseState May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 09:55:30 volumio volumio[1008]: error: Upnp client error: Error: This socket has been ended by the other party May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand status took 6 milliseconds May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand status took 5 milliseconds May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::parseState May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::parseState May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::pushState May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6 Double Orange Juice","artist":"Mezzoforte","album":"Observations","uri":"USB/4480-39C8/5 Observations/6 Double Orange Juice.mp3","trackType":"mp3"} May 30 09:55:30 volumio volumio[1008]: verbose: CURRENT POSITION 6 May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: No code May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: ------------------------------ 30ms May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 18 milliseconds May 30 09:55:30 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 18 milliseconds May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo May 30 09:55:30 volumio shairport-sync[14473]: % Total % Received % Xferd Average Speed Time Time Time Current May 30 09:55:30 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::pushState May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio shairport-sync[14473]: Dload Upload Total Spent Left Speed May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6 Double Orange Juice","artist":"Mezzoforte","album":"Observations","uri":"USB/4480-39C8/5 Observations/6 Double Orange Juice.mp3","trackType":"mp3"} May 30 09:55:30 volumio volumio[1008]: verbose: CURRENT POSITION 6 May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: No code May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: ControllerMpd::pushState May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6 Double Orange Juice","artist":"Mezzoforte","album":"Observations","uri":"USB/4480-39C8/5 Observations/6 Double Orange Juice.mp3","trackType":"mp3"} May 30 09:55:30 volumio volumio[1008]: verbose: CURRENT POSITION 6 May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: No code May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: ------------------------------ 54ms May 30 09:55:30 volumio volumio[1008]: info: ------------------------------ 54ms May 30 09:55:30 volumio volumio[1008]: info: Updating volume from AirPlay: -18; 40% May 30 09:55:30 volumio volumio[1008]: info: VolumeController::SetAlsaVolume40 May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 30 09:55:30 volumio volumio[1008]: info: May 30 09:55:30 volumio volumio[1008]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 30 09:55:30 volumio volumio[1008]: info: Airplay playback start May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::volumioStop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::stop May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:55:30 volumio shairport-sync[14473]: [237B blob data] May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 30 09:55:30 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:30 volumio volumio[1008]: info: CorePlayQueue::getTrack 6 May 30 09:55:30 volumio volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:31 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:31 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:31 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::servicePushState May 30 09:55:31 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:31 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:32 volumio volumio[1008]: info: Updating volume from AirPlay: -8.4; 72% May 30 09:55:32 volumio volumio[1008]: info: VolumeController::SetAlsaVolume72 May 30 09:55:32 volumio volumio[1008]: info: CoreStateMachine::pushState May 30 09:55:32 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:55:32 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState May 30 09:55:42 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 09:55:42 volumio volumio[1008]: info: Received Get System Info May 30 09:55:42 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 09:55:42 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 09:55:42 volumio volumio[1008]: info: Discovery: Getting this device information May 30 09:55:42 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState May 30 09:55:42 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 09:56:18 volumio volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 09:56:18 volumio volumio[1008]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net May 30 09:56:18 volumio volumio[1008]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 30 09:56:18 volumio volumio[1008]: errno: -3007, May 30 09:56:18 volumio volumio[1008]: code: 'ENOTFOUND', May 30 09:56:18 volumio volumio[1008]: syscall: 'getaddrinfo', May 30 09:56:18 volumio volumio[1008]: hostname: 'lastfm.freetls.fastly.net' May 30 09:56:18 volumio volumio[1008]: } May 30 09:56:18 volumio volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 09:56:18 volumio sudo[17134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-30 09:55 May 30 09:56:18 volumio sudo[17134]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"