-- Logs begin at Wed 2025-07-09 14:09:52 UTC, end at Wed 2025-07-09 14:21:35 UTC. -- Jul 09 14:20:01 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:05 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:09 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:13 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:17 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:21 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:25 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:29 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:33 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:37 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:41 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:45 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:49 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:53 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:57 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jul 09 14:20:58 volumio777 shairport-sync[1897]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Jul 09 14:20:58 volumio777 shairport-sync[1897]: alsa: error -16 ("Device or resource busy") opening alsa device "plughw:2,0". Jul 09 14:20:58 volumio777 volumio[990]: info: Airplay started streaming, receiving metadatas Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioStop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: UNSET VOLATILE Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::stPlaybackTimer Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::updateTrackBlock Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrackBlock Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:58 volumio777 shairport-sync[1897]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 14:20:58 volumio777 shairport-sync[1897]: Dload Upload Total Spent Left Speed Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::serviceStop Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::serviceStop Jul 09 14:20:58 volumio777 volumio[990]: info: ControllerMpd::stop Jul 09 14:20:58 volumio777 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: info: Airplay started streaming, receiving metadatas Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioStop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: UNSET VOLATILE Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: info: Airplay started streaming, receiving metadatas Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioStop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: UNSET VOLATILE Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: info: Airplay started streaming, receiving metadatas Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioStop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: UNSET VOLATILE Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 shairport-sync[1897]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 14:20:58 volumio777 shairport-sync[1897]: Dload Upload Total Spent Left Speed Jul 09 14:20:58 volumio777 volumio[990]: info: Jul 09 14:20:58 volumio777 volumio[990]: ---------------------------- Client requests AirplayActive Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Jul 09 14:20:58 volumio777 volumio[990]: info: Jul 09 14:20:58 volumio777 volumio[990]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 09 14:20:58 volumio777 shairport-sync[1897]: [155B blob data] Jul 09 14:20:58 volumio777 volumio[990]: info: Airplay playback start Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioStop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 volumio[990]: UNSET VOLATILE Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 14:20:58 volumio777 shairport-sync[1897]: [136B blob data] Jul 09 14:20:58 volumio777 volumio[990]: info: Jul 09 14:20:58 volumio777 volumio[990]: ---------------------------- MPD announces state update: player Jul 09 14:20:58 volumio777 volumio[990]: info: ControllerMpd::getState Jul 09 14:20:58 volumio777 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jul 09 14:20:58 volumio777 volumio[990]: verbose: ControllerMpd::parseState Jul 09 14:20:58 volumio777 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 14:20:58 volumio777 volumio[990]: verbose: ControllerMpd::parseTrackInfo Jul 09 14:20:58 volumio777 volumio[990]: info: ControllerMpd::pushState Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: 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":"사랑에 베이다","artist":"July","album":"Happiness","uri":"USB/MUSIC/02 - 사랑에 베이다.mp3","trackType":"mp3"} Jul 09 14:20:58 volumio777 volumio[990]: verbose: CURRENT POSITION 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::syncState stateService stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:58 volumio777 volumio[990]: info: No code Jul 09 14:20:58 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:58 volumio777 volumio[990]: info: CorePlayQueue::getTrack 117 Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:58 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:58 volumio777 volumio[990]: info: ------------------------------ 53ms Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::servicePushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreStateMachine::pushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::volumioPushState Jul 09 14:20:59 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 14:21:01 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:21:05 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:21:09 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:21:13 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:21:17 volumio777 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 09 14:21:33 volumio777 volumio[990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 14:21:33 volumio777 volumio[990]: { Error: connect ETIMEDOUT 128.242.240.218:443 Jul 09 14:21:33 volumio777 volumio[990]: at Object._errnoException (util.js:1022:11) Jul 09 14:21:33 volumio777 volumio[990]: at _exceptionWithHostPort (util.js:1044:20) Jul 09 14:21:33 volumio777 volumio[990]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 09 14:21:33 volumio777 volumio[990]: code: 'ETIMEDOUT', Jul 09 14:21:33 volumio777 volumio[990]: errno: 'ETIMEDOUT', Jul 09 14:21:33 volumio777 volumio[990]: syscall: 'connect', Jul 09 14:21:33 volumio777 volumio[990]: address: '128.242.240.218', Jul 09 14:21:33 volumio777 volumio[990]: port: 443 } Jul 09 14:21:33 volumio777 volumio[990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 14:21:35 volumio777 sudo[3050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 14:20 Jul 09 14:21:35 volumio777 sudo[3050]: 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"