-- Logs begin at Fri 2024-10-18 00:23:21 UTC, end at Fri 2024-10-18 13:18:46 UTC. -- Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Oct 18 13:18:11 volumio shairport-sync[1435]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Oct 18 13:18:11 volumio shairport-sync[1435]: alsa: error -16 ("Device or resource busy") opening alsa device "plughw:2,0". Oct 18 13:18:11 volumio volumio[899]: info: Airplay started streaming, receiving metadatas Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioStop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::stop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 13:18:11 volumio volumio[899]: UNSET VOLATILE Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::stPlaybackTimer Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::updateTrackBlock Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrackBlock Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::serviceStop Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::serviceStop Oct 18 13:18:11 volumio volumio[899]: info: ControllerMpd::stop Oct 18 13:18:11 volumio volumio[899]: verbose: ControllerMpd::sendMpdCommand stop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 13:18:11 volumio volumio[899]: info: Airplay started streaming, receiving metadatas Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioStop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::stop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 13:18:11 volumio volumio[899]: UNSET VOLATILE Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 13:18:11 volumio shairport-sync[1435]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 18 13:18:11 volumio shairport-sync[1435]: Dload Upload Total Spent Left Speed Oct 18 13:18:11 volumio volumio[899]: info: Oct 18 13:18:11 volumio volumio[899]: ---------------------------- Client requests AirplayActive Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Oct 18 13:18:11 volumio shairport-sync[1435]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Oct 18 13:18:11 volumio volumio[899]: info: Oct 18 13:18:11 volumio volumio[899]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 18 13:18:11 volumio volumio[899]: info: Airplay playback start Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioStop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::stop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 13:18:11 volumio volumio[899]: UNSET VOLATILE Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 13:18:11 volumio shairport-sync[1435]: 1 0 0D l o a d5 7 U p1l0o0a d 5T7o t a l 0 S p e n 0t 1 0L7ef4t2 S p e e d0 Oct 18 13:18:11 volumio shairport-sync[1435]: [58B blob data] Oct 18 13:18:11 volumio shairport-sync[1435]: [184B blob data] Oct 18 13:18:11 volumio volumio[899]: info: Oct 18 13:18:11 volumio volumio[899]: ---------------------------- MPD announces state update: player Oct 18 13:18:11 volumio volumio[899]: info: ControllerMpd::getState Oct 18 13:18:11 volumio volumio[899]: verbose: ControllerMpd::sendMpdCommand status Oct 18 13:18:11 volumio volumio[899]: verbose: ControllerMpd::parseState Oct 18 13:18:11 volumio volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 13:18:11 volumio volumio[899]: verbose: ControllerMpd::parseTrackInfo Oct 18 13:18:11 volumio volumio[899]: info: ControllerMpd::pushState Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: 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":"????","album":null,"uri":"NAS/港台女/D/丁倩/丁倩 1989 中国名曲精华 中国音乐家音像美国版/Track 01.wav","trackType":"wav"} Oct 18 13:18:11 volumio volumio[899]: verbose: CURRENT POSITION 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::syncState stateService stop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::syncState currentStatus stop Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:11 volumio volumio[899]: info: No code Oct 18 13:18:11 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:11 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:11 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:11 volumio volumio[899]: info: ------------------------------ 20ms Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:12 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:12 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:12 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:12 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:13 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:13 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:13 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:13 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:13 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:24 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:24 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:24 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::servicePushState Oct 18 13:18:24 volumio volumio[899]: info: CoreStateMachine::pushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Oct 18 13:18:24 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 13:18:46 volumio volumio[899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 13:18:46 volumio volumio[899]: { Error: connect ETIMEDOUT 210.56.51.192:443 Oct 18 13:18:46 volumio volumio[899]: at Object._errnoException (util.js:1022:11) Oct 18 13:18:46 volumio volumio[899]: at _exceptionWithHostPort (util.js:1044:20) Oct 18 13:18:46 volumio volumio[899]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 18 13:18:46 volumio volumio[899]: code: 'ETIMEDOUT', Oct 18 13:18:46 volumio volumio[899]: errno: 'ETIMEDOUT', Oct 18 13:18:46 volumio volumio[899]: syscall: 'connect', Oct 18 13:18:46 volumio volumio[899]: address: '210.56.51.192', Oct 18 13:18:46 volumio volumio[899]: port: 443 } Oct 18 13:18:46 volumio volumio[899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 13:18:46 volumio sudo[13381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 13:17 Oct 18 13:18:46 volumio sudo[13381]: 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"