-- Logs begin at Sun 2025-04-27 15:28:12 UTC, end at Sat 2025-05-24 07:27:39 UTC. -- May 24 07:26:56 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:56 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:56 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:56 volumio volumio[27585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 24 07:26:56 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:56 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:56 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:56 volumio volumio[27585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation May 24 07:26:57 volumio shairport-sync[18234]: % Total % Received % Xferd A vera g%e TSopteaeld % Received % Xferd Average Speed Time Time Time Current May 24 07:26:57 volumio shairport-sync[18234]: Dload Upload Total Spent Left Speed May 24 07:26:57 volumio shairport-sync[18234]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 Time Time Time Current May 24 07:26:57 volumio shairport-sync[18234]: Dload Upload Total Spent Left Speed May 24 07:26:57 volumio volumio[27585]: info: May 24 07:26:57 volumio volumio[27585]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 24 07:26:57 volumio volumio[27585]: info: Airplay playback start May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::volumioStop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::stop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 07:26:57 volumio volumio[27585]: UNSET VOLATILE May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::updateTrackBlock May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrackBlock May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::stPlaybackTimer May 24 07:26:57 volumio kernel: bcm2835-i2s 20203000.i2s: I2S SYNC error! May 24 07:26:57 volumio shairport-sync[18234]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::serviceStop May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::serviceStop May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::stop May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::sendMpdCommand stop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 07:26:57 volumio volumio[27585]: info: sending command... May 24 07:26:57 volumio shairport-sync[18234]: 100 64 100 64 0 0 226 0 --:--:-- --:--:-- --:--:-- 251 May 24 07:26:57 volumio volumio[27585]: info: Airplay started streaming, receiving metadatas May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::volumioStop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::stop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 07:26:57 volumio volumio[27585]: UNSET VOLATILE May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 07:26:57 volumio volumio[27585]: info: Airplay started streaming, receiving metadatas May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::volumioStop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::stop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 07:26:57 volumio volumio[27585]: UNSET VOLATILE May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 07:26:57 volumio volumio[27585]: info: May 24 07:26:57 volumio volumio[27585]: ---------------------------- MPD announces state update: player May 24 07:26:57 volumio shairport-sync[18234]: [143B blob data] May 24 07:26:57 volumio volumio[27585]: info: parsing response... May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::getState May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::sendMpdCommand status May 24 07:26:57 volumio volumio[27585]: info: sending command... May 24 07:26:57 volumio volumio[27585]: info: parsing response... May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::parseState May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::sendMpdCommand playlistinfo May 24 07:26:57 volumio volumio[27585]: info: sending command... May 24 07:26:57 volumio volumio[27585]: info: May 24 07:26:57 volumio volumio[27585]: ---------------------------- Client requests AirplayActive May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive May 24 07:26:57 volumio volumio[27585]: info: parsing response... May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::parseTrackInfo May 24 07:26:57 volumio volumio[27585]: info: ControllerMpd::pushState May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: 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":"ギターと孤独と蒼い惑星","artist":"結束バンド","album":"結束バンド","uri":"NAS/SharedMusics/結束バンド/結束バンド/05 ギターと孤独と蒼い惑星.flac","trackType":"flac"} May 24 07:26:57 volumio volumio[27585]: info: CURRENT POSITION 29 May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::syncState stateService stop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::syncState currentStatus stop May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:57 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:57 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:57 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:57 volumio volumio[27585]: info: No code May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:58 volumio volumio[27585]: info: CorePlayQueue::getTrack 29 May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:58 volumio volumio[27585]: info: ------------------------------ 763ms May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:58 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:58 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:26:59 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:26:59 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:27:05 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:27:05 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:27:05 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:27:05 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:27:05 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:27:05 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:27:05 volumio volumio[27585]: info: CoreCommandRouter::servicePushState May 24 07:27:05 volumio volumio[27585]: info: CoreStateMachine::syncState May 24 07:27:05 volumio volumio[27585]: info: CoreStateMachine::pushState May 24 07:27:05 volumio volumio[27585]: info: CoreStateMachine::getState May 24 07:27:05 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState May 24 07:27:05 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 07:27:32 volumio volumio[27585]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 07:27:32 volumio volumio[27585]: { Error: connect ETIMEDOUT 128.242.240.117:443 May 24 07:27:32 volumio volumio[27585]: at Object._errnoException (util.js:1022:11) May 24 07:27:32 volumio volumio[27585]: at _exceptionWithHostPort (util.js:1044:20) May 24 07:27:32 volumio volumio[27585]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 24 07:27:32 volumio volumio[27585]: code: 'ETIMEDOUT', May 24 07:27:32 volumio volumio[27585]: errno: 'ETIMEDOUT', May 24 07:27:32 volumio volumio[27585]: syscall: 'connect', May 24 07:27:32 volumio volumio[27585]: address: '128.242.240.117', May 24 07:27:32 volumio volumio[27585]: port: 443 } May 24 07:27:32 volumio volumio[27585]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 07:27:39 volumio sudo[18840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 07:26 May 24 07:27:39 volumio sudo[18840]: 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="ac2a0afddd424ea15d982b8c75eeec3a8648a313" VOLUMIO_FE_VERSION="700492006632ef61a5a17e6a3be4f37c790f883c" VOLUMIO_BE_VERSION="b2e85b2e8facd2d741f2fc40be426a34804b56fc" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue May 5 18:38:33 CEST 2020" VOLUMIO_VERSION="2.773" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="9e13d1d50d1f834c8c0294a226468074"