-- Logs begin at Fri 2025-08-29 15:33:15 UTC, end at Sun 2025-08-31 08:03:50 UTC. -- Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: 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} Aug 31 08:03:12 volumio volumio[983]: verbose: CURRENT POSITION 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState stateService play Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: 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} Aug 31 08:03:12 volumio volumio[983]: verbose: CURRENT POSITION 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState stateService play Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:03:12 volumio volumio[983]: info: Received an update from plugin. extracting info from payload Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio shairport-sync[1194]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Aug 31 08:03:12 volumio volumio[983]: info: Airplay started streaming, receiving metadatas Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioStop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::stop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:03:12 volumio shairport-sync[1194]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 08:03:12 volumio shairport-sync[1194]: Dload Upload Total Spent Left Speed Aug 31 08:03:12 volumio volumio[983]: verbose: UNSET VOLATILE Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::stPlaybackTimer Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio shairport-sync[1194]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Aug 31 08:03:12 volumio shairport-sync[1194]: Dload Upload Total Spent Left Speed Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::serviceStop Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: ControllerMpd::stop Aug 31 08:03:12 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:03:12 volumio volumio[983]: info: Airplay started streaming, receiving metadatas Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioStop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::stop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:03:12 volumio volumio[983]: verbose: UNSET VOLATILE Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:03:12 volumio volumio[983]: info: sendMpdCommand stop took 34 milliseconds Aug 31 08:03:12 volumio volumio[983]: info: Aug 31 08:03:12 volumio volumio[983]: ---------------------------- Client requests Start Airplay PlaybackRoutine Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Aug 31 08:03:12 volumio volumio[983]: info: Airplay playback start Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioStop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::stop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:03:12 volumio volumio[983]: verbose: UNSET VOLATILE Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:03:12 volumio shairport-sync[1194]: [155B blob data] Aug 31 08:03:12 volumio volumio[983]: info: Aug 31 08:03:12 volumio volumio[983]: ---------------------------- Client requests AirplayActive Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Aug 31 08:03:12 volumio shairport-sync[1194]: [143B blob data] Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"独りんぼエンヴィー","artist":"koyori(電ポルP)","album":"Youthfull","albumart":"/albumart?cacheid=502&web=koyori(%E9%9B%BB%E3%83%9D%E3%83%ABP)/Youthfull/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} Aug 31 08:03:12 volumio volumio[983]: verbose: CURRENT POSITION 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState stateService play Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"独りんぼエンヴィー","artist":"koyori(電ポルP)","album":"Youthfull","albumart":"/albumart?cacheid=502&web=koyori(%E9%9B%BB%E3%83%9D%E3%83%ABP)/Youthfull/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} Aug 31 08:03:12 volumio volumio[983]: verbose: CURRENT POSITION 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState stateService play Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:03:12 volumio volumio[983]: info: Received an update from plugin. extracting info from payload Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"独りんぼエンヴィー","artist":"koyori(電ポルP)","album":"Youthfull","albumart":"/albumart?cacheid=502&web=koyori(%E9%9B%BB%E3%83%9D%E3%83%ABP)/Youthfull/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} Aug 31 08:03:12 volumio volumio[983]: verbose: CURRENT POSITION 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState stateService play Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:03:12 volumio volumio[983]: info: Received an update from plugin. extracting info from payload Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"独りんぼエンヴィー","artist":"koyori(電ポルP)","album":"Youthfull","albumart":"/albumart?cacheid=502&web=koyori(%E9%9B%BB%E3%83%9D%E3%83%ABP)/Youthfull/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} Aug 31 08:03:12 volumio volumio[983]: verbose: CURRENT POSITION 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState stateService play Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:03:12 volumio volumio[983]: info: Received an update from plugin. extracting info from payload Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:12 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:12 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:13 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:13 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::servicePushState Aug 31 08:03:13 volumio volumio[983]: info: CoreStateMachine::pushState Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Aug 31 08:03:13 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:03:48 volumio volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 08:03:48 volumio volumio[983]: { Error: connect ETIMEDOUT 31.13.69.169:443 Aug 31 08:03:48 volumio volumio[983]: at Object._errnoException (util.js:1022:11) Aug 31 08:03:48 volumio volumio[983]: at _exceptionWithHostPort (util.js:1044:20) Aug 31 08:03:48 volumio volumio[983]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 31 08:03:48 volumio volumio[983]: code: 'ETIMEDOUT', Aug 31 08:03:48 volumio volumio[983]: errno: 'ETIMEDOUT', Aug 31 08:03:48 volumio volumio[983]: syscall: 'connect', Aug 31 08:03:48 volumio volumio[983]: address: '31.13.69.169', Aug 31 08:03:48 volumio volumio[983]: port: 443 } Aug 31 08:03:48 volumio volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 08:03:50 volumio sudo[3316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 08:02 Aug 31 08:03:50 volumio sudo[3316]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"