-- Logs begin at Sat 2021-04-24 15:39:58 UTC, end at Tue 2025-05-27 04:05:01 UTC. -- May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 04:04:10 localhost volumio[856]: verbose: CURRENT POSITION 0 May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::syncState stateService play May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::syncState currentStatus stop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 04:04:10 localhost volumio[856]: verbose: CURRENT POSITION 0 May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::syncState stateService play May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::syncState currentStatus play May 27 04:04:10 localhost volumio[856]: info: Received an update from plugin. extracting info from payload May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 04:04:10 localhost volumio[856]: verbose: CURRENT POSITION 0 May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::syncState stateService play May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::syncState currentStatus play May 27 04:04:10 localhost volumio[856]: info: Received an update from plugin. extracting info from payload May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:10 localhost shairport-sync[1779]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 04:04:10 localhost shairport-sync[1779]: Dload Upload Total Spent Left Speed May 27 04:04:10 localhost shairport-sync[1779]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory May 27 04:04:10 localhost shairport-sync[1779]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 04:04:10 localhost shairport-sync[1779]: Dload Upload Total Spent Left Speed May 27 04:04:10 localhost volumio[856]: info: Airplay started streaming, receiving metadatas May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioStop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::stop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: UNSET VOLATILE May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::stPlaybackTimer May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::updateTrackBlock May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrackBlock May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::serviceStop May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: ControllerMpd::stop May 27 04:04:10 localhost volumio[856]: verbose: ControllerMpd::sendMpdCommand stop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: info: Airplay started streaming, receiving metadatas May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioStop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::stop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: UNSET VOLATILE May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: info: Airplay started streaming, receiving metadatas May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioStop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::stop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: UNSET VOLATILE May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: info: May 27 04:04:10 localhost volumio[856]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 27 04:04:10 localhost volumio[856]: info: Airplay playback start May 27 04:04:10 localhost volumio[856]: info: CorePlayQueue::getTrack 0 May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::volumioStop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::stop May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: UNSET VOLATILE May 27 04:04:10 localhost volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 04:04:10 localhost volumio[856]: info: May 27 04:04:10 localhost volumio[856]: ---------------------------- Client requests AirplayActive May 27 04:04:10 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive May 27 04:04:10 localhost shairport-sync[1779]: [155B blob data] May 27 04:04:10 localhost volumio[856]: info: sendMpdCommand stop took 31 milliseconds May 27 04:04:10 localhost shairport-sync[1779]: [143B blob data] May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:11 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:11 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:11 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:11 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:11 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:12 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:12 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:12 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:12 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:12 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:12 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:12 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:20 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:20 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:20 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:20 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:20 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::servicePushState May 27 04:04:20 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:20 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:28 localhost volumio[856]: info: CoreCommandRouter::volumioGetState May 27 04:04:28 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 27 04:04:28 localhost volumio[856]: info: Listing playlists May 27 04:04:28 localhost volumio[856]: info: CoreCommandRouter::volumioGetQueue May 27 04:04:28 localhost volumio[856]: info: CoreStateMachine::getQueue May 27 04:04:28 localhost volumio[856]: info: CorePlayQueue::getQueue May 27 04:04:28 localhost volumio[856]: info: CoreCommandRouter::volumioGetVisibleSources May 27 04:04:28 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 04:04:31 localhost volumio[856]: info: VolumeController::SetAlsaVolume45 May 27 04:04:31 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:31 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:31 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:31 localhost volumio[856]: info: VolumeController::SetAlsaVolume60 May 27 04:04:31 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:31 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:31 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:31 localhost volumio[856]: info: VolumeController::SetAlsaVolume65 May 27 04:04:31 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:31 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:31 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:32 localhost volumio[856]: info: VolumeController::SetAlsaVolume70 May 27 04:04:32 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:32 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:32 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:04:32 localhost volumio[856]: info: VolumeController::SetAlsaVolume90 May 27 04:04:32 localhost volumio[856]: info: CoreStateMachine::pushState May 27 04:04:32 localhost volumio[856]: info: CoreCommandRouter::volumioPushState May 27 04:04:32 localhost volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 04:05:00 localhost volumio[856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 04:05:00 localhost volumio[856]: { Error: connect ETIMEDOUT 108.160.169.178:443 May 27 04:05:00 localhost volumio[856]: at Object._errnoException (util.js:1022:11) May 27 04:05:00 localhost volumio[856]: at _exceptionWithHostPort (util.js:1044:20) May 27 04:05:00 localhost volumio[856]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 27 04:05:00 localhost volumio[856]: code: 'ETIMEDOUT', May 27 04:05:00 localhost volumio[856]: errno: 'ETIMEDOUT', May 27 04:05:00 localhost volumio[856]: syscall: 'connect', May 27 04:05:00 localhost volumio[856]: address: '108.160.169.178', May 27 04:05:00 localhost volumio[856]: port: 443 } May 27 04:05:00 localhost volumio[856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 04:05:01 localhost sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-27 04:04 May 27 04:05:01 localhost sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="74f64fbba9dc8053dc27ed7125863d6ad6533965" VOLUMIO_BE_VERSION="0a6008ded215bf284d5d4cd9c4df3333cb320899" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 24 17:39:58 CEST 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1a98a01eef5aa5927a5874c2d3c1b0dc"