-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-03-11 08:36:25 UTC. -- Mar 11 08:35:05 volumio volumio[1457]: info: Airplay started streaming, receiving metadatas Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioStop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::updateTrackBlock Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrackBlock Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::stPlaybackTimer Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::serviceStop Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::serviceStop Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::stop Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio volumio[1457]: info: Airplay started streaming, receiving metadatas Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioStop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio volumio[1457]: info: Airplay started streaming, receiving metadatas Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioStop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio shairport-sync[1610]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 11 08:35:05 volumio shairport-sync[1610]: Dload Upload Total Spent Left Speed Mar 11 08:35:05 volumio volumio[1457]: info: Mar 11 08:35:05 volumio volumio[1457]: ---------------------------- MPD announces state update: player Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand stop took 31 milliseconds Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::getState Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand status Mar 11 08:35:05 volumio volumio[1457]: info: Mar 11 08:35:05 volumio volumio[1457]: ---------------------------- MPD announces state update: player Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::getState Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand status Mar 11 08:35:05 volumio volumio[1457]: info: Mar 11 08:35:05 volumio volumio[1457]: ---------------------------- MPD announces state update: player Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::getState Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand status Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand status took 7 milliseconds Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand status took 6 milliseconds Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand status took 4 milliseconds Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::parseState Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::parseState Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::parseState Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 11 08:35:05 volumio volumio[1457]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::parseTrackInfo Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::parseTrackInfo Mar 11 08:35:05 volumio volumio[1457]: verbose: ControllerMpd::parseTrackInfo Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: 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":"01 - Stravinsky - The Rite of Spring - Part 1","artist":null,"album":null,"uri":"NAS/music/好音乐/百大古典经典黑胶唱片完整记录 珍藏版 100张 24-96 LP/Volume 42 - Russian Orchestral Masterpieces/Record 83/01 - Stravinsky - The Rite of Spring - Part 1.flac","trackType":"flac"} Mar 11 08:35:05 volumio volumio[1457]: verbose: CURRENT POSITION 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::syncState stateService stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::syncState currentStatus stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: No code Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: 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":"01 - Stravinsky - The Rite of Spring - Part 1","artist":null,"album":null,"uri":"NAS/music/好音乐/百大古典经典黑胶唱片完整记录 珍藏版 100张 24-96 LP/Volume 42 - Russian Orchestral Masterpieces/Record 83/01 - Stravinsky - The Rite of Spring - Part 1.flac","trackType":"flac"} Mar 11 08:35:05 volumio volumio[1457]: verbose: CURRENT POSITION 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::syncState stateService stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::syncState currentStatus stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: No code Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: ControllerMpd::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: 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":"01 - Stravinsky - The Rite of Spring - Part 1","artist":null,"album":null,"uri":"NAS/music/好音乐/百大古典经典黑胶唱片完整记录 珍藏版 100张 24-96 LP/Volume 42 - Russian Orchestral Masterpieces/Record 83/01 - Stravinsky - The Rite of Spring - Part 1.flac","trackType":"flac"} Mar 11 08:35:05 volumio volumio[1457]: verbose: CURRENT POSITION 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::syncState stateService stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::syncState currentStatus stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: No code Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:05 volumio volumio[1457]: info: ------------------------------ 114ms Mar 11 08:35:05 volumio volumio[1457]: info: ------------------------------ 113ms Mar 11 08:35:05 volumio volumio[1457]: info: ------------------------------ 112ms Mar 11 08:35:05 volumio volumio[1457]: info: Mar 11 08:35:05 volumio volumio[1457]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 11 08:35:05 volumio volumio[1457]: info: Airplay playback start Mar 11 08:35:05 volumio volumio[1457]: info: CorePlayQueue::getTrack 83 Mar 11 08:35:05 volumio volumio[1457]: info: CoreCommandRouter::volumioStop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::stop Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio volumio[1457]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 08:35:05 volumio shairport-sync[1610]: [158B blob data] Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:06 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:35:06 volumio volumio[1457]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/moon%20tang/25/4c5e62ae-983d-46d2-8deb-1168d987617e.jpg' Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:35:06 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::servicePushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreStateMachine::pushState Mar 11 08:36:24 volumio volumio[1457]: info: CoreCommandRouter::volumioPushState Mar 11 08:36:25 volumio volumio[1457]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 08:36:25 volumio volumio[1457]: Error: connect ECONNREFUSED 199.59.148.7:443 Mar 11 08:36:25 volumio volumio[1457]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 11 08:36:25 volumio volumio[1457]: errno: -111, Mar 11 08:36:25 volumio volumio[1457]: code: 'ECONNREFUSED', Mar 11 08:36:25 volumio volumio[1457]: syscall: 'connect', Mar 11 08:36:25 volumio volumio[1457]: address: '199.59.148.7', Mar 11 08:36:25 volumio volumio[1457]: port: 443 Mar 11 08:36:25 volumio volumio[1457]: } Mar 11 08:36:25 volumio volumio[1457]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 08:36:25 volumio sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 08:35 Mar 11 08:36:25 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"