-- Logs begin at Fri 2024-05-24 15:34:10 UTC, end at Sat 2024-08-24 09:41:29 UTC. -- Aug 24 09:40:02 musical-fidelity volumio[4419]: info: Aug 24 09:40:02 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:02 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:02 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:02 musical-fidelity volumio[4419]: info: sendMpdCommand status took 1 milliseconds Aug 24 09:40:02 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:02 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:02 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 09:40:02 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:02 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:02 musical-fidelity volumio[4419]: 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":"Don't You Worry","artist":"Oh Wonder","album":"Home Tapes","uri":"http://sjy6.stream.qqmusic.qq.com/M800000IMraY3hozM4.mp3?guid=00000000411549570000018c24bfe7bf&vkey=1350619E2046E8DCA6723A57FD0C35C8D4046FF52C2BE6E092AE9AB14C181773196F99E23562965A994E966D026A0F249BE647A326B0FB76&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:02 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService stop Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreStateMachine::play index undefined Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:02 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:02 musical-fidelity volumio[4419]: info: ------------------------------ 23ms Aug 24 09:40:02 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:02 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Clearing queue after UPNP request Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreStateMachine::ClearQueue Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreStateMachine::stop Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:03 musical-fidelity volumio[4419]: verbose: UNSET VOLATILE Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CorePlayQueue::clearPlayQueue Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CorePlayQueue::saveQueue Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushQueue Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: error: updateQueue error: null Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Starting UPNP Playback Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Preparing playback through UPNP Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioGetState Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:03 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 1ms Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Aug 24 09:40:03 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:03 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Aug 24 09:40:04 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:04 musical-fidelity volumio[4419]: info: sendMpdCommand status took 8 milliseconds Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Aug 24 09:40:04 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Aug 24 09:40:04 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:04 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 24 09:40:04 musical-fidelity volumio[4419]: info: sendMpdCommand status took 5 milliseconds Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Open Your Eyes","artist":"School of Seven Bells","album":"Open Your Eyes","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800003dzZX70X4E3P.mp3?guid=00000000411549570000018c24bfe7bf&vkey=00D5E1A863CA451FA1037957FB3B92237F958E4A8AFF689DF194E00A93E90CDD95E074CD24F94E5A9B4F5E3BCAC17958F1F1C50229624509&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus stop Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ------------------------------ 31ms Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ------------------------------ 26ms Aug 24 09:40:04 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Open Your Eyes","artist":"School of Seven Bells","album":"Open Your Eyes","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800003dzZX70X4E3P.mp3?guid=00000000411549570000018c24bfe7bf&vkey=00D5E1A863CA451FA1037957FB3B92237F958E4A8AFF689DF194E00A93E90CDD95E074CD24F94E5A9B4F5E3BCAC17958F1F1C50229624509&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Received an update from plugin. extracting info from payload Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ------------------------------ 58ms Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Aug 24 09:40:04 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:04 musical-fidelity volumio[4419]: info: sendMpdCommand status took 4 milliseconds Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: 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":"Open Your Eyes","artist":"School of Seven Bells","album":"Open Your Eyes","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800003dzZX70X4E3P.mp3?guid=00000000411549570000018c24bfe7bf&vkey=00D5E1A863CA451FA1037957FB3B92237F958E4A8AFF689DF194E00A93E90CDD95E074CD24F94E5A9B4F5E3BCAC17958F1F1C50229624509&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService stop Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::play index undefined Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ------------------------------ 34ms Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:04 musical-fidelity volumio[4419]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Oh%20Wonder/Home%20Tapes/9d82fdeb-f13f-4a31-82e4-be081b197e5c.jpg' Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Clearing queue after UPNP request Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::ClearQueue Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::stop Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:04 musical-fidelity volumio[4419]: verbose: UNSET VOLATILE Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CorePlayQueue::clearPlayQueue Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CorePlayQueue::saveQueue Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushQueue Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Aug 24 09:40:04 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:04 musical-fidelity volumio[4419]: error: updateQueue error: null Aug 24 09:40:04 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Starting UPNP Playback Aug 24 09:40:04 musical-fidelity volumio[4419]: info: Preparing playback through UPNP Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioGetState Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:04 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:05 musical-fidelity volumio[4419]: info: sendMpdCommand status took 7 milliseconds Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Aug 24 09:40:05 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:05 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2002","artist":"Anne-Marie","album":"2002","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800002sR5SD4CXTqW.mp3?guid=00000000411549570000018c24bfe7bf&vkey=00A25FA3C6B3472C499E8C6D80747369B9A1A6FBC184AB38295431F9E9E6F624BB9E82D64607849C8937649B48E0066006FFA54C0743F4D2&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus stop Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 30ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 22ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: sendMpdCommand status took 18 milliseconds Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:05 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2002","artist":"Anne-Marie","album":"2002","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800002sR5SD4CXTqW.mp3?guid=00000000411549570000018c24bfe7bf&vkey=00A25FA3C6B3472C499E8C6D80747369B9A1A6FBC184AB38295431F9E9E6F624BB9E82D64607849C8937649B48E0066006FFA54C0743F4D2&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:05 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Received an update from plugin. extracting info from payload Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:05 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:05 musical-fidelity volumio[4419]: info: ------------------------------ 63ms Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:05 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:05 musical-fidelity volumio[4419]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/School%20of%20Seven%20Bells/Open%20Your%20Eyes/9add8982-e670-4b87-a27e-f79925b79483.jpg' Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Aug 24 09:40:06 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:06 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:06 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:06 musical-fidelity volumio[4419]: info: sendMpdCommand status took 1 milliseconds Aug 24 09:40:06 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:06 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:06 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 09:40:06 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:06 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:06 musical-fidelity volumio[4419]: 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":"2002","artist":"Anne-Marie","album":"2002","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800002sR5SD4CXTqW.mp3?guid=00000000411549570000018c24bfe7bf&vkey=00A25FA3C6B3472C499E8C6D80747369B9A1A6FBC184AB38295431F9E9E6F624BB9E82D64607849C8937649B48E0066006FFA54C0743F4D2&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:06 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService stop Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::play index undefined Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:06 musical-fidelity volumio[4419]: info: ------------------------------ 25ms Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Clearing queue after UPNP request Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::ClearQueue Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::stop Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:06 musical-fidelity volumio[4419]: verbose: UNSET VOLATILE Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CorePlayQueue::clearPlayQueue Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CorePlayQueue::saveQueue Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushQueue Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Aug 24 09:40:06 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:06 musical-fidelity volumio[4419]: error: updateQueue error: null Aug 24 09:40:06 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Starting UPNP Playback Aug 24 09:40:06 musical-fidelity volumio[4419]: info: Preparing playback through UPNP Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioGetState Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:06 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Aug 24 09:40:07 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:07 musical-fidelity volumio[4419]: info: sendMpdCommand status took 7 milliseconds Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 5ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: sendMpdCommand status took 3 milliseconds Aug 24 09:40:07 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"All We Know","artist":"The Chainsmokers","album":"All We Know","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800000yYgkz3iINE3.mp3?guid=00000000411549570000018c24bfe7bf&vkey=48685FA8E7246D796CD78A8EFA1066DA2C397C3FAD325F3DBBBA3559EC07352266459F42807E8ABF1CE83719278ECB1C4B5A81F5E15CB39F&uin=2855958070&src=M800002Cd9Wb3rkPyX.mp3&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus stop Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 45ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 32 milliseconds Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"All We Know","artist":"The Chainsmokers","album":"All We Know","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800000yYgkz3iINE3.mp3?guid=00000000411549570000018c24bfe7bf&vkey=48685FA8E7246D796CD78A8EFA1066DA2C397C3FAD325F3DBBBA3559EC07352266459F42807E8ABF1CE83719278ECB1C4B5A81F5E15CB39F&uin=2855958070&src=M800002Cd9Wb3rkPyX.mp3&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:07 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Received an update from plugin. extracting info from payload Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:07 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:07 musical-fidelity volumio[4419]: info: ------------------------------ 76ms Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:07 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:07 musical-fidelity volumio[4419]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Anne-Marie/2002/9b491501-1f55-4c9f-8c72-22178525b855.jpg' Aug 24 09:40:11 musical-fidelity volumio[4419]: info: Aug 24 09:40:11 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:11 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:11 musical-fidelity volumio[4419]: info: sendMpdCommand status took 1 milliseconds Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:11 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:11 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174000,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"All We Know","artist":"The Chainsmokers","album":"All We Know","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800000yYgkz3iINE3.mp3?guid=00000000411549570000018c24bfe7bf&vkey=48685FA8E7246D796CD78A8EFA1066DA2C397C3FAD325F3DBBBA3559EC07352266459F42807E8ABF1CE83719278ECB1C4B5A81F5E15CB39F&uin=2855958070&src=M800002Cd9Wb3rkPyX.mp3&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:11 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:11 musical-fidelity volumio[4419]: info: Received an update from plugin. extracting info from payload Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:11 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:11 musical-fidelity volumio[4419]: info: ------------------------------ 32ms Aug 24 09:40:11 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:11 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:32 musical-fidelity volumio[4419]: info: Aug 24 09:40:32 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:32 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:32 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:32 musical-fidelity volumio[4419]: info: sendMpdCommand status took 4 milliseconds Aug 24 09:40:32 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:32 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 24 09:40:32 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService stop Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreStateMachine::play index undefined Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:32 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:32 musical-fidelity volumio[4419]: info: ------------------------------ 34ms Aug 24 09:40:32 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:32 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:36 musical-fidelity volumio[4419]: info: Clearing queue after UPNP request Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::ClearQueue Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::stop Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: UNSET VOLATILE Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CorePlayQueue::clearPlayQueue Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CorePlayQueue::saveQueue Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushQueue Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: error: updateQueue error: null Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 4ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Starting UPNP Playback Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Preparing playback through UPNP Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioGetState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 3ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Aug 24 09:40:37 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:37 musical-fidelity volumio[4419]: info: sendMpdCommand status took 10 milliseconds Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 8ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: sendMpdCommand status took 6 milliseconds Aug 24 09:40:37 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"golden hour","artist":"JVKE","album":"this is what ____ feels like (Vol. 1-4)","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800003SC8S50M1qRZ.mp3?guid=00000000411549570000018c24bfe7bf&vkey=1A514FC92B5DFF00A78DFD9AEC93A12BCA67F365265388DC65AE32E8A33E7ADF044673C3195ED1A3D675B0B906DEC9FB1F73F14EA70D167B&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus stop Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 30ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"golden hour","artist":"JVKE","album":"this is what ____ feels like (Vol. 1-4)","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800003SC8S50M1qRZ.mp3?guid=00000000411549570000018c24bfe7bf&vkey=1A514FC92B5DFF00A78DFD9AEC93A12BCA67F365265388DC65AE32E8A33E7ADF044673C3195ED1A3D675B0B906DEC9FB1F73F14EA70D167B&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:37 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Received an update from plugin. extracting info from payload Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:37 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:37 musical-fidelity volumio[4419]: info: ------------------------------ 48ms Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:37 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:38 musical-fidelity volumio[4419]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Chainsmokers/All%20We%20Know/dbf5a754-8d15-4916-9b85-7c723315a6e4.jpg' Aug 24 09:40:44 musical-fidelity volumio[4419]: info: Aug 24 09:40:44 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:44 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:44 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:44 musical-fidelity volumio[4419]: info: sendMpdCommand status took 3 milliseconds Aug 24 09:40:44 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:44 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:44 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 09:40:44 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:44 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:44 musical-fidelity volumio[4419]: 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":"golden hour","artist":"JVKE","album":"this is what ____ feels like (Vol. 1-4)","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800003SC8S50M1qRZ.mp3?guid=00000000411549570000018c24bfe7bf&vkey=1A514FC92B5DFF00A78DFD9AEC93A12BCA67F365265388DC65AE32E8A33E7ADF044673C3195ED1A3D675B0B906DEC9FB1F73F14EA70D167B&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:44 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService stop Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreStateMachine::play index undefined Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:44 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:44 musical-fidelity volumio[4419]: info: ------------------------------ 25ms Aug 24 09:40:44 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:44 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:44 musical-fidelity volumio[4419]: info: Clearing queue after UPNP request Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::ClearQueue Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::stop Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: UNSET VOLATILE Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CorePlayQueue::clearPlayQueue Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CorePlayQueue::saveQueue Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushQueue Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Starting UPNP Playback Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Preparing playback through UPNP Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioGetState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CorePlayQueue::getTrack 0 Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 09:40:45 musical-fidelity volumio[4419]: error: updateQueue error: null Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 14ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 1ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 1ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 2ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 1ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 1ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:45 musical-fidelity volumio[4419]: info: sendMpdCommand status took 7 milliseconds Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces system playlist update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Ignoring MPD Status Update Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Aug 24 09:40:45 musical-fidelity volumio[4419]: ---------------------------- MPD announces state update: player Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ControllerMpd::getState Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand status Aug 24 09:40:45 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 6ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: sendMpdCommand status took 6 milliseconds Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseState Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Worry","artist":"Oh Wonder","album":"Home Tapes","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800000IMraY3hozM4.mp3?guid=00000000411549570000018c24bfe7bf&vkey=1350619E2046E8DCA6723A57FD0C35C8D4046FF52C2BE6E092AE9AB14C181773196F99E23562965A994E966D026A0F249BE647A326B0FB76&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus stop Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 37ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: ControllerMpd::parseTrackInfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ControllerMpd::pushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::servicePushState Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: In UPNP mode Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Don't You Worry","artist":"Oh Wonder","album":"Home Tapes","uri":"http://125.74.6.97/amobile.music.tc.qq.com/M800000IMraY3hozM4.mp3?guid=00000000411549570000018c24bfe7bf&vkey=1350619E2046E8DCA6723A57FD0C35C8D4046FF52C2BE6E092AE9AB14C181773196F99E23562965A994E966D026A0F249BE647A326B0FB76&uin=2855958070&redirect=1&fromtag=111093","trackType":""} Aug 24 09:40:45 musical-fidelity volumio[4419]: verbose: CURRENT POSITION 0 Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState stateService play Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::syncState currentStatus play Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Received an update from plugin. extracting info from payload Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreStateMachine::pushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::volumioPushState Aug 24 09:40:45 musical-fidelity volumio[4419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 09:40:45 musical-fidelity volumio[4419]: info: ------------------------------ 51ms Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:45 musical-fidelity volumio[4419]: info: Updating RAAT Signal Path Aug 24 09:40:46 musical-fidelity volumio[4419]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/JVKE/this%20is%20what%20____%20feels%20like%20(Vol.%201-4)/d42921f0-3824-4fd9-a273-4bead56358ef.jpg' Aug 24 09:40:46 musical-fidelity volumio[4419]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Oh%20Wonder/Home%20Tapes/9d82fdeb-f13f-4a31-82e4-be081b197e5c.jpg' Aug 24 09:40:55 musical-fidelity wpa_supplicant[952]: wlan0: WPA: Group rekeying completed with 36:0d:9e:bc:41:0c [GTK=CCMP] Aug 24 09:40:56 musical-fidelity kernel: RTL871X: send eapol packet Aug 24 09:40:56 musical-fidelity kernel: RTL871X: set group key camid:5, addr:36:0d:9e:bc:41:0c, kid:1, type:AES Aug 24 09:41:28 musical-fidelity volumio[4419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 09:41:28 musical-fidelity volumio[4419]: { Error: connect ETIMEDOUT 173.252.105.21:443 Aug 24 09:41:28 musical-fidelity volumio[4419]: at Object._errnoException (util.js:1022:11) Aug 24 09:41:28 musical-fidelity volumio[4419]: at _exceptionWithHostPort (util.js:1044:20) Aug 24 09:41:28 musical-fidelity volumio[4419]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 24 09:41:28 musical-fidelity volumio[4419]: code: 'ETIMEDOUT', Aug 24 09:41:28 musical-fidelity volumio[4419]: errno: 'ETIMEDOUT', Aug 24 09:41:28 musical-fidelity volumio[4419]: syscall: 'connect', Aug 24 09:41:28 musical-fidelity volumio[4419]: address: '173.252.105.21', Aug 24 09:41:28 musical-fidelity volumio[4419]: port: 443 } Aug 24 09:41:28 musical-fidelity volumio[4419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 09:41:29 musical-fidelity sudo[12466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 09:40 Aug 24 09:41:29 musical-fidelity sudo[12466]: 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="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"