May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 21:30:02 volumio volumio[1251]: info: Received Get System Info May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:30:02 volumio volumio[1251]: info: Discovery: Getting this device information May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 21:30:02 volumio volumio[1251]: info: Received Get System Info May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:30:02 volumio volumio[1251]: info: Discovery: Getting this device information May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 28 21:30:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 28 21:30:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 21:30:05 volumio volumio[1251]: info: CoreCommandRouter::volumioGetQueue May 28 21:30:05 volumio volumio[1251]: info: CoreStateMachine::getQueue May 28 21:30:05 volumio volumio[1251]: info: CorePlayQueue::getQueue May 28 21:30:05 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 21:30:07 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:10 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:10 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::serviceStop May 28 21:30:10 volumio volumio[1251]: info: CoreCommandRouter::serviceStop May 28 21:30:10 volumio volumio[1251]: info: Airplay Stop May 28 21:30:10 volumio volumio[1251]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:10 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:30:10 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:10 volumio volumio[1251]: info: Adding Item to queue: https://www.jazz88.org/listen.m3u May 28 21:30:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 21:30:10 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:30:10 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:30:10 volumio volumio[1251]: verbose: UNSET VOLATILE: Service: airplay_emulation May 28 21:30:10 volumio volumio[1251]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:10 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:30:10 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:10 volumio volumio[1251]: info: [1780032610522] ControllerWebradio::clearAddPlayTrack May 28 21:30:10 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:30:10 volumio volumio[1251]: info: sendMpdCommand stop took 6 milliseconds May 28 21:30:10 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:30:10 volumio volumio[1251]: info: May 28 21:30:10 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:10 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:10 volumio volumio[1251]: info: sendMpdCommand clear took 4 milliseconds May 28 21:30:10 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "https://www.jazz88.org/listen.m3u" May 28 21:30:10 volumio volumio[1251]: info: May 28 21:30:10 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:10 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:10 volumio volumio[1251]: info: May 28 21:30:10 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:10 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:10 volumio sudo[5222]: pam_unix(sudo:account): account volumio has password changed in future May 28 21:30:10 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:10 volumio volumio[1251]: info: ------------------------------ 19ms May 28 21:30:10 volumio sudo[5222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 25153 May 28 21:30:10 volumio sudo[5222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 21:30:10 volumio sudo[5222]: pam_unix(sudo:session): session closed for user root May 28 21:30:10 volumio volumio[1251]: info: Shairport-Sync paused with USR2 May 28 21:30:10 volumio shairport-sync[5159]: {"time":1780032580816,"response":"airplayInactive Success"} May 28 21:30:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 May 28 21:30:10 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. May 28 21:30:10 volumio sudo[5225]: pam_unix(sudo:account): account volumio has password changed in future May 28 21:30:10 volumio systemd[1]: shairport-sync.service: Consumed 28min 13.074s CPU time. May 28 21:30:10 volumio sudo[5225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 25153 May 28 21:30:10 volumio sudo[5225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 21:30:10 volumio sudo[5225]: pam_unix(sudo:session): session closed for user root May 28 21:30:10 volumio volumio[1251]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) May 28 21:30:10 volumio volumio[1251]: /bin/kill: (25153): No such process May 28 21:30:11 volumio volumio[1251]: info: Restarting Shairport-Sync after stop May 28 21:30:11 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:11 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "https://www.jazz88.org/listen.m3u" May 28 21:30:11 volumio volumio[1251]: info: ------------------------------ 1090ms May 28 21:30:11 volumio volumio[1251]: info: ------------------------------ 1088ms May 28 21:30:11 volumio volumio[1251]: info: sendMpdCommand add "https://www.jazz88.org/listen.m3u" took 1 milliseconds May 28 21:30:11 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 21:30:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:30:11 volumio volumio[1251]: info: May 28 21:30:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:11 volumio volumio[1251]: info: May 28 21:30:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:11 volumio volumio[1251]: info: May 28 21:30:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:11 volumio sudo[5230]: pam_unix(sudo:account): account volumio has password changed in future May 28 21:30:11 volumio volumio[1251]: info: sendMpdCommand play took 14 milliseconds May 28 21:30:11 volumio volumio[1251]: info: ------------------------------ 14ms May 28 21:30:11 volumio volumio[1251]: info: ------------------------------ 13ms May 28 21:30:11 volumio volumio[1251]: info: ------------------------------ 11ms May 28 21:30:11 volumio sudo[5230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 21:30:11 volumio sudo[5230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 21:30:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 21:30:11 volumio sudo[5230]: pam_unix(sudo:session): session closed for user root May 28 21:30:11 volumio volumio[1251]: info: Shairport-Sync retarted May 28 21:30:12 volumio volumio[1251]: info: May 28 21:30:12 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:12 volumio volumio[1251]: info: May 28 21:30:12 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:12 volumio volumio[1251]: info: May 28 21:30:12 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:12 volumio volumio[1251]: info: May 28 21:30:12 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:12 volumio volumio[1251]: info: May 28 21:30:12 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:12 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand status took 20 milliseconds May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:12 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand status took 18 milliseconds May 28 21:30:12 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand status took 18 milliseconds May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:12 volumio volumio[1251]: info: May 28 21:30:12 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:12 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand status took 29 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand clearerror took 22 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand status took 22 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand clearerror took 21 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand clearerror took 19 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 13 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 13 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 12 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand status took 9 milliseconds May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:12 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: No code May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:12 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: No code May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:12 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: No code May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: ------------------------------ 116ms May 28 21:30:12 volumio volumio[1251]: info: ------------------------------ 116ms May 28 21:30:12 volumio volumio[1251]: info: ------------------------------ 113ms May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand clearerror took 87 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 83 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 83 milliseconds May 28 21:30:12 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 80 milliseconds May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:12 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:12 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: No code May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:12 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: No code May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:12 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: No code May 28 21:30:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:12 volumio volumio[1251]: info: ------------------------------ 174ms May 28 21:30:12 volumio volumio[1251]: info: ------------------------------ 173ms May 28 21:30:12 volumio volumio[1251]: info: ------------------------------ 155ms May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 21:30:18 volumio volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 28 21:30:18 volumio volumio[1251]: info: Received Get System Version May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 21:30:18 volumio volumio[1251]: info: Received Get System Info May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:30:18 volumio volumio[1251]: info: Discovery: Getting this device information May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 28 21:30:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:30:19 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:19 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:19 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:30:19 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:19 volumio volumio[1251]: info: Adding Item to queue: https://www.jazz88.org/listen.m3u May 28 21:30:19 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 21:30:19 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:30:19 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:19 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:30:19 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:19 volumio volumio[1251]: info: [1780032619081] ControllerWebradio::clearAddPlayTrack May 28 21:30:19 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:30:19 volumio volumio[1251]: info: sendMpdCommand stop took 2 milliseconds May 28 21:30:19 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:30:19 volumio volumio[1251]: info: May 28 21:30:19 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:19 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:19 volumio volumio[1251]: info: sendMpdCommand clear took 1 milliseconds May 28 21:30:19 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "https://www.jazz88.org/listen.m3u" May 28 21:30:19 volumio volumio[1251]: info: May 28 21:30:19 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:19 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:19 volumio volumio[1251]: info: May 28 21:30:19 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:19 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:19 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:19 volumio volumio[1251]: info: ------------------------------ 6ms May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "https://www.jazz88.org/listen.m3u" May 28 21:30:20 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:20 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:20 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:20 volumio volumio[1251]: info: ------------------------------ 932ms May 28 21:30:20 volumio volumio[1251]: info: ------------------------------ 930ms May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand add "https://www.jazz88.org/listen.m3u" took 4 milliseconds May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:20 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:20 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:20 volumio volumio[1251]: info: ------------------------------ 12ms May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand play took 11 milliseconds May 28 21:30:20 volumio volumio[1251]: info: ------------------------------ 6ms May 28 21:30:20 volumio volumio[1251]: info: ------------------------------ 3ms May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:20 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand status took 10 milliseconds May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:20 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:20 volumio volumio[1251]: info: May 28 21:30:20 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:20 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://www.jazz88.org/listen.m3u"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand status took 22 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand clearerror took 19 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand status took 19 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand clearerror took 18 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 12 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 12 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand status took 9 milliseconds May 28 21:30:20 volumio volumio[1251]: info: sendMpdCommand status took 7 milliseconds May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:20 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:20 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:20 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:20 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:20 volumio volumio[1251]: info: No code May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:20 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:20 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:20 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:20 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:20 volumio volumio[1251]: info: No code May 28 21:30:20 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:20 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: ------------------------------ 78ms May 28 21:30:21 volumio volumio[1251]: info: ------------------------------ 78ms May 28 21:30:21 volumio volumio[1251]: info: sendMpdCommand clearerror took 58 milliseconds May 28 21:30:21 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 55 milliseconds May 28 21:30:21 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 54 milliseconds May 28 21:30:21 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 54 milliseconds May 28 21:30:21 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 53 milliseconds May 28 21:30:21 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:21 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:21 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:21 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:21 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:21 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: No code May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:21 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: No code May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:21 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: No code May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:21 volumio volumio[1251]: 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":"listen.m3u","artist":null,"album":null,"uri":"https://www.jazz88.org/listen.m3u","trackType":"m3u"} May 28 21:30:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: No code May 28 21:30:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:21 volumio volumio[1251]: info: ------------------------------ 145ms May 28 21:30:21 volumio volumio[1251]: info: ------------------------------ 147ms May 28 21:30:21 volumio volumio[1251]: info: ------------------------------ 133ms May 28 21:30:21 volumio volumio[1251]: info: ------------------------------ 133ms May 28 21:30:22 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:22 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:22 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:30:22 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:22 volumio volumio[1251]: info: Adding Item to queue: http://www.wwoz.org/listen/hi May 28 21:30:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 21:30:22 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:30:22 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:22 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:30:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:22 volumio volumio[1251]: info: [1780032622760] ControllerWebradio::clearAddPlayTrack May 28 21:30:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:30:22 volumio volumio[1251]: info: sendMpdCommand stop took 3 milliseconds May 28 21:30:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:30:22 volumio volumio[1251]: info: May 28 21:30:22 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:22 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:22 volumio volumio[1251]: info: sendMpdCommand clear took 2 milliseconds May 28 21:30:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "http://www.wwoz.org/listen/hi" May 28 21:30:22 volumio volumio[1251]: info: May 28 21:30:22 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:22 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:22 volumio volumio[1251]: info: May 28 21:30:22 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:22 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:24 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:24 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:24 volumio volumio[1251]: info: ------------------------------ 1765ms May 28 21:30:24 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "http://www.wwoz.org/listen/hi" May 28 21:30:24 volumio volumio[1251]: info: ------------------------------ 1764ms May 28 21:30:24 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:24 volumio volumio[1251]: info: ------------------------------ 1771ms May 28 21:30:24 volumio volumio[1251]: info: sendMpdCommand add "http://www.wwoz.org/listen/hi" took 9 milliseconds May 28 21:30:24 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 21:30:24 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:30:24 volumio volumio[1251]: info: May 28 21:30:24 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:24 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:24 volumio volumio[1251]: info: sendMpdCommand play took 7 milliseconds May 28 21:30:24 volumio volumio[1251]: info: May 28 21:30:24 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:24 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:24 volumio volumio[1251]: info: May 28 21:30:24 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:24 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:24 volumio volumio[1251]: info: ------------------------------ 6ms May 28 21:30:24 volumio volumio[1251]: info: ------------------------------ 5ms May 28 21:30:24 volumio volumio[1251]: info: ------------------------------ 3ms May 28 21:30:25 volumio volumio[1251]: info: May 28 21:30:25 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:25 volumio volumio[1251]: info: May 28 21:30:25 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:25 volumio volumio[1251]: info: May 28 21:30:25 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:25 volumio volumio[1251]: info: May 28 21:30:25 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:25 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "http://www.wwoz.org/listen/hi"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:25 volumio volumio[1251]: info: May 28 21:30:25 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:25 volumio volumio[1251]: info: May 28 21:30:25 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:25 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "http://www.wwoz.org/listen/hi"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand status took 18 milliseconds May 28 21:30:25 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "http://www.wwoz.org/listen/hi"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand status took 17 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand clearerror took 12 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand status took 13 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 12 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand status took 10 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:25 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:25 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: No code May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: ------------------------------ 47ms May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand clearerror took 34 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand clearerror took 33 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 29 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 28 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 28 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 27 milliseconds May 28 21:30:25 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 27 milliseconds May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:25 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:25 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:25 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: No code May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:25 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:25 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: No code May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:25 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:25 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: No code May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:25 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:25 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: No code May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:25 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:25 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: No code May 28 21:30:25 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:25 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:25 volumio volumio[1251]: info: ------------------------------ 127ms May 28 21:30:25 volumio volumio[1251]: info: ------------------------------ 128ms May 28 21:30:25 volumio volumio[1251]: info: ------------------------------ 128ms May 28 21:30:25 volumio volumio[1251]: info: ------------------------------ 122ms May 28 21:30:25 volumio volumio[1251]: info: ------------------------------ 123ms May 28 21:30:33 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:33 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:33 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:30:33 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:33 volumio volumio[1251]: info: Adding Item to queue: http://www.wwoz.org/listen/hi May 28 21:30:33 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 21:30:33 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:30:33 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:33 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:30:33 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:33 volumio volumio[1251]: info: [1780032633073] ControllerWebradio::clearAddPlayTrack May 28 21:30:33 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:30:33 volumio volumio[1251]: info: sendMpdCommand stop took 3 milliseconds May 28 21:30:33 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:30:33 volumio volumio[1251]: info: May 28 21:30:33 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:33 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:33 volumio volumio[1251]: info: sendMpdCommand clear took 2 milliseconds May 28 21:30:33 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "http://www.wwoz.org/listen/hi" May 28 21:30:33 volumio volumio[1251]: info: May 28 21:30:33 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:33 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:33 volumio volumio[1251]: info: May 28 21:30:33 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:33 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:33 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:33 volumio volumio[1251]: info: ------------------------------ 5ms May 28 21:30:34 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "http://www.wwoz.org/listen/hi" May 28 21:30:34 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:34 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:34 volumio volumio[1251]: info: May 28 21:30:34 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:34 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:34 volumio volumio[1251]: info: ------------------------------ 1351ms May 28 21:30:34 volumio volumio[1251]: info: ------------------------------ 1350ms May 28 21:30:34 volumio volumio[1251]: info: sendMpdCommand add "http://www.wwoz.org/listen/hi" took 5 milliseconds May 28 21:30:34 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 21:30:34 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:30:34 volumio volumio[1251]: info: May 28 21:30:34 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:34 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:34 volumio volumio[1251]: info: May 28 21:30:34 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:34 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:34 volumio volumio[1251]: info: ------------------------------ 9ms May 28 21:30:34 volumio volumio[1251]: info: sendMpdCommand play took 8 milliseconds May 28 21:30:34 volumio volumio[1251]: info: ------------------------------ 7ms May 28 21:30:34 volumio volumio[1251]: info: ------------------------------ 3ms May 28 21:30:35 volumio volumio[1251]: info: May 28 21:30:35 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:35 volumio volumio[1251]: info: May 28 21:30:35 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:35 volumio volumio[1251]: info: May 28 21:30:35 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:35 volumio volumio[1251]: info: May 28 21:30:35 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:35 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "http://www.wwoz.org/listen/hi"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand status took 7 milliseconds May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:35 volumio volumio[1251]: info: May 28 21:30:35 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:35 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "http://www.wwoz.org/listen/hi"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand status took 15 milliseconds May 28 21:30:35 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "http://www.wwoz.org/listen/hi"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand status took 15 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand clearerror took 11 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand status took 10 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 9 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:35 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:35 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: No code May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: ------------------------------ 54ms May 28 21:30:35 volumio volumio[1251]: info: May 28 21:30:35 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand clearerror took 44 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand clearerror took 42 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 39 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 38 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 38 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 31 milliseconds May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand status took 7 milliseconds May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:35 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:35 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: No code May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:35 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:35 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: No code May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:35 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:35 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: No code May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:35 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:35 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: No code May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: ------------------------------ 128ms May 28 21:30:35 volumio volumio[1251]: info: ------------------------------ 129ms May 28 21:30:35 volumio volumio[1251]: info: ------------------------------ 131ms May 28 21:30:35 volumio volumio[1251]: info: ------------------------------ 125ms May 28 21:30:35 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 75 milliseconds May 28 21:30:35 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:35 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:35 volumio volumio[1251]: 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":"hi","artist":null,"album":null,"uri":"http://www.wwoz.org/listen/hi","trackType":""} May 28 21:30:35 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: No code May 28 21:30:35 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:35 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:35 volumio volumio[1251]: info: ------------------------------ 110ms May 28 21:30:42 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:42 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:42 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:30:42 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:42 volumio volumio[1251]: info: Adding Item to queue: https://knkx-live-a.edge.audiocdn.com/6284_256k May 28 21:30:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 21:30:42 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:30:42 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:42 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:30:42 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:42 volumio volumio[1251]: info: [1780032642928] ControllerWebradio::clearAddPlayTrack May 28 21:30:42 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:30:42 volumio volumio[1251]: info: sendMpdCommand stop took 2 milliseconds May 28 21:30:42 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:30:42 volumio volumio[1251]: info: sendMpdCommand clear took 1 milliseconds May 28 21:30:42 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "https://knkx-live-a.edge.audiocdn.com/6284_256k" May 28 21:30:42 volumio volumio[1251]: info: May 28 21:30:42 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:42 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:42 volumio volumio[1251]: info: May 28 21:30:42 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:42 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:42 volumio volumio[1251]: info: May 28 21:30:42 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:42 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:43 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "https://knkx-live-a.edge.audiocdn.com/6284_256k" May 28 21:30:43 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:43 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:43 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:43 volumio volumio[1251]: info: ------------------------------ 993ms May 28 21:30:43 volumio volumio[1251]: info: ------------------------------ 991ms May 28 21:30:43 volumio volumio[1251]: info: ------------------------------ 990ms May 28 21:30:43 volumio volumio[1251]: info: sendMpdCommand add "https://knkx-live-a.edge.audiocdn.com/6284_256k" took 3 milliseconds May 28 21:30:43 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 21:30:43 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:30:43 volumio volumio[1251]: info: May 28 21:30:43 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:43 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:43 volumio volumio[1251]: info: May 28 21:30:43 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:43 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:43 volumio volumio[1251]: info: May 28 21:30:43 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:43 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:43 volumio volumio[1251]: info: sendMpdCommand play took 8 milliseconds May 28 21:30:43 volumio volumio[1251]: info: ------------------------------ 7ms May 28 21:30:43 volumio volumio[1251]: info: ------------------------------ 5ms May 28 21:30:43 volumio volumio[1251]: info: ------------------------------ 4ms May 28 21:30:44 volumio volumio[1251]: info: May 28 21:30:44 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:44 volumio volumio[1251]: info: May 28 21:30:44 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:44 volumio volumio[1251]: info: May 28 21:30:44 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:44 volumio volumio[1251]: info: May 28 21:30:44 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:44 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://knkx-live-a.edge.audiocdn.com/6284_256k"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:44 volumio volumio[1251]: info: May 28 21:30:44 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:44 volumio volumio[1251]: info: May 28 21:30:44 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:44 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://knkx-live-a.edge.audiocdn.com/6284_256k"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand status took 16 milliseconds May 28 21:30:44 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://knkx-live-a.edge.audiocdn.com/6284_256k"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand status took 16 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand clearerror took 13 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand status took 12 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 11 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand status took 10 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:44 volumio volumio[1251]: 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":"6284_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6284_256k","trackType":""} May 28 21:30:44 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: No code May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: ------------------------------ 53ms May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand clearerror took 39 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand clearerror took 38 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 34 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 33 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 32 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 32 milliseconds May 28 21:30:44 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 32 milliseconds May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:44 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:44 volumio volumio[1251]: 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":"6284_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6284_256k","trackType":""} May 28 21:30:44 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: No code May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:44 volumio volumio[1251]: 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":"6284_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6284_256k","trackType":""} May 28 21:30:44 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: No code May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:44 volumio volumio[1251]: 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":"6284_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6284_256k","trackType":""} May 28 21:30:44 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: No code May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:44 volumio volumio[1251]: 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":"6284_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6284_256k","trackType":""} May 28 21:30:44 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: No code May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:44 volumio volumio[1251]: 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":"6284_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6284_256k","trackType":""} May 28 21:30:44 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: No code May 28 21:30:44 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:44 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:44 volumio volumio[1251]: info: ------------------------------ 129ms May 28 21:30:44 volumio volumio[1251]: info: ------------------------------ 128ms May 28 21:30:44 volumio volumio[1251]: info: ------------------------------ 129ms May 28 21:30:44 volumio volumio[1251]: info: ------------------------------ 123ms May 28 21:30:44 volumio volumio[1251]: info: ------------------------------ 123ms May 28 21:30:47 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:47 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:47 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:30:47 volumio volumio[1251]: info: Preload queue cleared May 28 21:30:47 volumio volumio[1251]: info: Adding Item to queue: https://knkx-live-a.edge.audiocdn.com/6285_256k May 28 21:30:47 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 21:30:47 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:30:47 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:47 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:30:47 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:47 volumio volumio[1251]: info: [1780032647305] ControllerWebradio::clearAddPlayTrack May 28 21:30:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:30:47 volumio volumio[1251]: info: sendMpdCommand stop took 1 milliseconds May 28 21:30:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:30:47 volumio volumio[1251]: info: May 28 21:30:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:47 volumio volumio[1251]: info: sendMpdCommand clear took 1 milliseconds May 28 21:30:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "https://knkx-live-a.edge.audiocdn.com/6285_256k" May 28 21:30:47 volumio volumio[1251]: info: May 28 21:30:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:47 volumio volumio[1251]: info: May 28 21:30:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:47 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:47 volumio volumio[1251]: info: ------------------------------ 6ms May 28 21:30:48 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "https://knkx-live-a.edge.audiocdn.com/6285_256k" May 28 21:30:48 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:48 volumio volumio[1251]: error: updateQueue error: null May 28 21:30:48 volumio volumio[1251]: info: ------------------------------ 841ms May 28 21:30:48 volumio volumio[1251]: info: ------------------------------ 840ms May 28 21:30:48 volumio volumio[1251]: info: sendMpdCommand add "https://knkx-live-a.edge.audiocdn.com/6285_256k" took 2 milliseconds May 28 21:30:48 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 21:30:48 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:30:48 volumio volumio[1251]: info: May 28 21:30:48 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:48 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:48 volumio volumio[1251]: info: sendMpdCommand play took 3 milliseconds May 28 21:30:48 volumio volumio[1251]: info: May 28 21:30:48 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:48 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:48 volumio volumio[1251]: info: May 28 21:30:48 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:30:48 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:30:48 volumio volumio[1251]: info: ------------------------------ 6ms May 28 21:30:48 volumio volumio[1251]: info: ------------------------------ 5ms May 28 21:30:48 volumio volumio[1251]: info: ------------------------------ 3ms May 28 21:30:49 volumio volumio[1251]: info: May 28 21:30:49 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:49 volumio volumio[1251]: info: May 28 21:30:49 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:49 volumio volumio[1251]: info: May 28 21:30:49 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:49 volumio volumio[1251]: info: May 28 21:30:49 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:49 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://knkx-live-a.edge.audiocdn.com/6285_256k"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand status took 7 milliseconds May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:49 volumio volumio[1251]: info: May 28 21:30:49 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:49 volumio volumio[1251]: info: May 28 21:30:49 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::getState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 28 21:30:49 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://knkx-live-a.edge.audiocdn.com/6285_256k"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand status took 16 milliseconds May 28 21:30:49 volumio volumio[1251]: error: MPD returned error for command status: Failed to decode "https://knkx-live-a.edge.audiocdn.com/6285_256k"; server certificate verification failed. CAfile: none CRLfile: none: SSL peer certificate or SSH remote key was not OK May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand status took 15 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand clearerror took 12 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand status took 12 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 10 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand status took 8 milliseconds May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseState May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:49 volumio volumio[1251]: 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":"6285_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6285_256k","trackType":""} May 28 21:30:49 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: No code May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: ------------------------------ 46ms May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand clearerror took 33 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand clearerror took 33 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 28 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 27 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 26 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 26 milliseconds May 28 21:30:49 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 26 milliseconds May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:49 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:49 volumio volumio[1251]: 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":"6285_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6285_256k","trackType":""} May 28 21:30:49 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: No code May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:49 volumio volumio[1251]: 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":"6285_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6285_256k","trackType":""} May 28 21:30:49 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: No code May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:49 volumio volumio[1251]: 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":"6285_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6285_256k","trackType":""} May 28 21:30:49 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: No code May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:49 volumio volumio[1251]: 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":"6285_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6285_256k","trackType":""} May 28 21:30:49 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: No code May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: ControllerMpd::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:30:49 volumio volumio[1251]: 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":"6285_256k","artist":null,"album":null,"uri":"https://knkx-live-a.edge.audiocdn.com/6285_256k","trackType":""} May 28 21:30:49 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: No code May 28 21:30:49 volumio volumio[1251]: info: CoreStateMachine::pushState May 28 21:30:49 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 28 21:30:49 volumio volumio[1251]: info: ------------------------------ 127ms May 28 21:30:49 volumio volumio[1251]: info: ------------------------------ 128ms May 28 21:30:49 volumio volumio[1251]: info: ------------------------------ 128ms May 28 21:30:49 volumio volumio[1251]: info: ------------------------------ 122ms May 28 21:30:49 volumio volumio[1251]: info: ------------------------------ 123ms May 28 21:30:55 volumio kernel: hwmon hwmon1: Voltage normalised May 28 21:30:57 volumio kernel: hwmon hwmon1: Undervoltage detected! May 28 21:30:59 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 28 21:31:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 28 21:31:08 volumio volumio[1251]: info: CURURI: music-library May 28 21:31:08 volumio volumio[1251]: info: Preload queue cleared May 28 21:31:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 28 21:31:11 volumio volumio[1251]: info: CURURI: music-library/USB May 28 21:31:11 volumio volumio[1251]: info: Preload queue cleared May 28 21:31:16 volumio bash[5356]: setdatetime-helper: all HTTPS Date fallbacks failed May 28 21:31:16 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 28 21:31:16 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 28 21:31:16 volumio systemd[1]: setdatetime-helper.service: Consumed 4.565s CPU time. May 28 21:31:22 volumio volumio[1251]: Searching plugin music_service/mpd May 28 21:31:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 28 21:31:23 volumio volumio[1251]: Searching plugin music_service/mpd May 28 21:31:23 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 28 21:31:24 volumio volumio[1251]: Searching plugin music_service/mpd May 28 21:31:24 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 28 21:31:27 volumio volumio[1251]: info: All search sources collected, pushing search results May 28 21:31:27 volumio volumio[1251]: info: All search sources collected, pushing search results May 28 21:31:27 volumio volumio[1251]: info: All search sources collected, pushing search results May 28 21:31:30 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:30 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:30 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:30 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:31 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:32 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:33 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:34 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:35 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:36 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:37 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/George%20Winston/Remembrance%3A%20A%20Memorial%20Benefit/308ac8a6-950d-4cfe-84d2-1cd35bca4354.jpg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:38 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:39 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Glen%20Hansard%20and%20Marketa%20Irglova/Once/b508aaae-bc6d-4f7e-87c6-e0ad8d8592ba.jpg' May 28 21:31:39 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:39 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:39 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:40 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:40 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:40 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:40 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:41 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:41 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:41 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:41 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:41 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:41 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:42 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mstislav%20Rostropovich/Bach%3A%20Cello%20Suites%20%231%2C%204%20%26%205%20%5BDisc%201%5D/3efea8e7-7152-4dec-b164-84a69d2cfcd8.jpg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:43 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:44 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:45 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:46 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:46 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:46 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:46 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:46 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:46 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:47 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:47 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:47 volumio volumio[1251]: info: Preload queue cleared May 28 21:31:47 volumio volumio[1251]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::ClearQueue May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::clearPlayQueue May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:31:47 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::addQueueItems May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::addQueueItems May 28 21:31:47 volumio volumio[1251]: info: Preload queue cleared May 28 21:31:47 volumio volumio[1251]: info: Adding Item to queue: albums://Miles%20Davis/Kind%20of%20Blue May 28 21:31:47 volumio volumio[1251]: info: Exploding uri albums://Miles%20Davis/Kind%20of%20Blue in service mpd May 28 21:31:47 volumio volumio[1251]: info: CoreCommandRouter::volumioPushQueue May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::saveQueue May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 28 21:31:47 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::play index 0 May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::stop May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:31:47 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 28 21:31:47 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 28 21:31:47 volumio volumio[1251]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_DISK/Music/Miles Davis/Kind of Blue/01 So What.m4a May 28 21:31:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 28 21:31:47 volumio volumio[1251]: info: sendMpdCommand stop took 34 milliseconds May 28 21:31:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 28 21:31:47 volumio volumio[1251]: info: May 28 21:31:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:31:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:31:47 volumio volumio[1251]: info: sendMpdCommand clear took 38 milliseconds May 28 21:31:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_DISK/Music/Miles Davis/Kind of Blue/01 So What.m4a" May 28 21:31:47 volumio volumio[1251]: info: May 28 21:31:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:31:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:31:47 volumio volumio[1251]: info: May 28 21:31:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:31:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:31:47 volumio volumio[1251]: error: updateQueue error: null May 28 21:31:47 volumio volumio[1251]: info: May 28 21:31:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:31:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:31:47 volumio volumio[1251]: info: ------------------------------ 54ms May 28 21:31:47 volumio volumio[1251]: info: sendMpdCommand add "USB/USB_DISK/Music/Miles Davis/Kind of Blue/01 So What.m4a" took 52 milliseconds May 28 21:31:47 volumio volumio[1251]: info: ------------------------------ 49ms May 28 21:31:47 volumio volumio[1251]: info: ------------------------------ 49ms May 28 21:31:47 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 28 21:31:47 volumio volumio[1251]: info: May 28 21:31:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:31:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:31:47 volumio volumio[1251]: info: May 28 21:31:47 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 28 21:31:47 volumio volumio[1251]: info: Ignoring MPD Status Update May 28 21:31:47 volumio volumio[1251]: info: ------------------------------ 13ms May 28 21:31:47 volumio volumio[1251]: info: sendMpdCommand play took 11 milliseconds May 28 21:31:47 volumio volumio[1251]: info: ------------------------------ 10ms May 28 21:31:47 volumio volumio[1251]: info: ------------------------------ 9ms May 28 21:31:47 volumio volumio[1251]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 28 21:31:48 volumio volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:31:48 volumio volumio[1251]: Error: certificate is not yet valid May 28 21:31:48 volumio volumio[1251]: at TLSSocket.onConnectSecure (node:_tls_wrap:1627:34) May 28 21:31:48 volumio volumio[1251]: at TLSSocket.emit (node:events:514:28) May 28 21:31:48 volumio volumio[1251]: at TLSSocket._finishInit (node:_tls_wrap:1038:8) May 28 21:31:48 volumio volumio[1251]: at ssl.onhandshakedone (node:_tls_wrap:824:12) { May 28 21:31:48 volumio volumio[1251]: code: 'CERT_NOT_YET_VALID' May 28 21:31:48 volumio volumio[1251]: } May 28 21:31:48 volumio volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:31:49 volumio sudo[5515]: pam_unix(sudo:account): account volumio has password changed in future May 28 21:31:49 volumio sudo[5515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 21:30' May 28 21:31:49 volumio sudo[5515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="ebd772339e193fb4b3a98c68e63a0009e72d6f27" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 14 07:39:23 UTC 2026" VOLUMIO_VERSION="4.204" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="915e71dafc26fd850574d238b4f947d5"