-- Logs begin at Fri 2024-08-30 13:41:30 UTC, end at Fri 2024-08-30 15:22:59 UTC. -- Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: Play playlist 13 Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:41 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: [1725031302002] ControllerWebradio::stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/france musique.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/france musique.mp3 in service mpd Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://direct.francemusique.fr/live/francemusique-midfi.mp3?ID=76zqey582k Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 136 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/france%20musique/extralarge&path=%2Fmnt%2FINTERNAL%2Ffrance%20musique.mp3&metadata=false Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/france musique.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/france musique.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 15 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 3 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 2 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/france musique.mp3" Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: 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":"mouv-midfi.mp3?ID=76zqey582k","artist":"mouv-midfi.mp3","album":null,"uri":"http://direct.mouv.fr/live/mouv-midfi.mp3?ID=76zqey582k","trackType":""} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 53ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 39ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 38ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/france musique.mp3" took 36 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 4ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 15ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 13 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 12ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 31 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 33 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 12 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 4 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"france musique","artist":"radio-tsf","album":"france musique","uri":"INTERNAL/france musique.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"france musique","artist":"radio-tsf","album":"france musique","uri":"INTERNAL/france musique.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 104ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 104ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 56 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":628,"duration":5,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"france musique","artist":"radio-tsf","album":"france musique","uri":"INTERNAL/france musique.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":697,"duration":5,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"france musique","artist":"radio-tsf","album":"france musique","uri":"INTERNAL/france musique.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 127ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 117ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Play playlist 12 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/tsf jazz.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/tsf jazz.mp3 in service mpd Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://tsfjazz.ice.infomaniak.ch/tsfjazz-high.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 84 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/tsf%20jazz/extralarge&path=%2Fmnt%2FINTERNAL%2Ftsf%20jazz.mp3&metadata=false Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/tsf jazz.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/tsf jazz.mp3 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 15 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 4 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 2 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/tsf jazz.mp3" Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: 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":"france musique","artist":"radio-tsf","album":"france musique","uri":"INTERNAL/france musique.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 55ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 41ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 37ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/tsf jazz.mp3" took 34 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 4ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 74ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 73 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 72ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 7 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 10 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 6 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":10,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"tsf jazz","artist":"radio-tsf","album":"tsf jazz","uri":"INTERNAL/tsf jazz.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":10,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"tsf jazz","artist":"radio-tsf","album":"tsf jazz","uri":"INTERNAL/tsf jazz.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 57ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 57ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 47 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 47 milliseconds Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":10,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"tsf jazz","artist":"radio-tsf","album":"tsf jazz","uri":"INTERNAL/tsf jazz.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":10,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"tsf jazz","artist":"radio-tsf","album":"tsf jazz","uri":"INTERNAL/tsf jazz.mp3","trackType":"mp3"} Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 104ms Aug 30 15:21:42 tsf-phillips-1956 volumio[745]: info: ------------------------------ 99ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Play playlist 11 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/france info.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/france info.mp3 in service mpd Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://direct.franceinfo.fr/live/franceinfo-midfi.mp3?ID=76zqey582k Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/france%20info/extralarge&path=%2Fmnt%2FINTERNAL%2Ffrance%20info.mp3&metadata=false Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/france info.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 81 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/france info.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 17 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 4 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 2 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 7 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/france info.mp3" Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: 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":"tsf jazz","artist":"radio-tsf","album":"tsf jazz","uri":"INTERNAL/tsf jazz.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 62ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 46ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 41ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/france info.mp3" took 39 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 5ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 27ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 26 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 25ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 7 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 6 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 14 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"france info","artist":"radio-tsf","album":"france info","uri":"INTERNAL/france info.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"france info","artist":"radio-tsf","album":"france info","uri":"INTERNAL/france info.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 81ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 78ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 60 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"france info","artist":"radio-tsf","album":"france info","uri":"INTERNAL/france info.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 100ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 57 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":640,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"france info","artist":"radio-tsf","album":"france info","uri":"INTERNAL/france info.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 146ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Play playlist 10 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/classic 21.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/classic 21.mp3 in service mpd Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://radios.rtbf.be/classic21-128.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/classic%2021/extralarge&path=%2Fmnt%2FINTERNAL%2Fclassic%2021.mp3&metadata=false Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/classic 21.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 90 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/classic 21.mp3 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 14 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 4 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 2 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 6 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/classic 21.mp3" Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: 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":"france info","artist":"radio-tsf","album":"france info","uri":"INTERNAL/france info.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 51ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 38ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 32ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/classic 21.mp3" took 30 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 4ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 21ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 20 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 16ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 7 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 13 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"classic 21","artist":"radio-tsf","album":"classic 21","uri":"INTERNAL/classic 21.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 49ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 38 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 33 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 3 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"classic 21","artist":"radio-tsf","album":"classic 21","uri":"INTERNAL/classic 21.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 95ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"classic 21","artist":"radio-tsf","album":"classic 21","uri":"INTERNAL/classic 21.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":6,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"classic 21","artist":"radio-tsf","album":"classic 21","uri":"INTERNAL/classic 21.mp3","trackType":"mp3"} Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 163ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: ------------------------------ 127ms Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:43 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Play playlist 9 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/sing sing.mp3 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/sing sing.mp3 in service mpd Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://stream.sing-sing-bis.org:8000/singsingFlac Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/sing%20sing/extralarge&path=%2Fmnt%2FINTERNAL%2Fsing%20sing.mp3&metadata=false Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/sing sing.mp3 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 36 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/sing sing.mp3 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 29 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 13 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 11 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 14 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/sing sing.mp3" Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: 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":"classic 21","artist":"radio-tsf","album":"classic 21","uri":"INTERNAL/classic 21.mp3","trackType":"mp3"} Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 83ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 61ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 39ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/sing sing.mp3" took 34 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 5ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 39ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 38 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 37ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 9 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 10 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 17 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 5 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":308,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sing sing","artist":"radio-tsf","album":"sing sing","uri":"INTERNAL/sing sing.mp3","trackType":"mp3"} Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":422,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sing sing","artist":"radio-tsf","album":"sing sing","uri":"INTERNAL/sing sing.mp3","trackType":"mp3"} Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 78ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 72ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 46 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":697,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sing sing","artist":"radio-tsf","album":"sing sing","uri":"INTERNAL/sing sing.mp3","trackType":"mp3"} Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":948,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sing sing","artist":"radio-tsf","album":"sing sing","uri":"INTERNAL/sing sing.mp3","trackType":"mp3"} Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 115ms Aug 30 15:21:44 tsf-phillips-1956 volumio[745]: info: ------------------------------ 101ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Play playlist 8 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/sun.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/sun.mp3 in service mpd Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://80.82.229.202:8000/sunhd.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 152 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/sun/extralarge&path=%2Fmnt%2FINTERNAL%2Fsun.mp3&metadata=false Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/sun.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/sun.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 20 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 4 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 3 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 7 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/sun.mp3" Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: 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":"sing sing","artist":"radio-tsf","album":"sing sing","uri":"INTERNAL/sing sing.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 60ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 42ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 36ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/sun.mp3" took 33 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 3ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 15ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 14 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 13ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 15 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 17 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 5 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sun","artist":"radio-tsf","album":"sun","uri":"INTERNAL/sun.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":4,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sun","artist":"radio-tsf","album":"sun","uri":"INTERNAL/sun.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 66ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 65ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 44 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 41 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":4,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sun","artist":"radio-tsf","album":"sun","uri":"INTERNAL/sun.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 69ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 40 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":4,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"sun","artist":"radio-tsf","album":"sun","uri":"INTERNAL/sun.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 116ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Play playlist 7 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/couleur 3.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/couleur 3.mp3 in service mpd Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: http://stream.srg-ssr.ch/couleur3/mp3_128.m3u Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 114 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/couleur%203/extralarge&path=%2Fmnt%2FINTERNAL%2Fcouleur%203.mp3&metadata=false Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/couleur 3.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/couleur 3.mp3 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 13 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 3 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 1 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 5 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/couleur 3.mp3" Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: 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":"sun","artist":"radio-tsf","album":"sun","uri":"INTERNAL/sun.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 47ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 35ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 32ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/couleur 3.mp3" took 30 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 6ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 17ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 14 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 13ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 16 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 18 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"couleur 3","artist":"radio-tsf","album":"couleur 3","uri":"INTERNAL/couleur 3.mp3","trackType":"mp3"} Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ------------------------------ 39ms Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 22 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:45 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"couleur 3","artist":"radio-tsf","album":"couleur 3","uri":"INTERNAL/couleur 3.mp3","trackType":"mp3"} Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: ------------------------------ 66ms Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 41 milliseconds Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":3,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"couleur 3","artist":"radio-tsf","album":"couleur 3","uri":"INTERNAL/couleur 3.mp3","trackType":"mp3"} Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: ------------------------------ 86ms Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":662,"duration":3,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"couleur 3","artist":"radio-tsf","album":"couleur 3","uri":"INTERNAL/couleur 3.mp3","trackType":"mp3"} Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:46 tsf-phillips-1956 volumio[745]: info: ------------------------------ 86ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Play playlist 6 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioClearQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::ClearQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stPlaybackTimer Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::serviceStop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::serviceStop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::clearPlayQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::addQueueItems Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::addQueueItems Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: INTERNAL/neo.mp3 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Exploding uri INTERNAL/neo.mp3 in service mpd Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Adding Item to queue: https://ice.creacast.com/radio-neo-paris Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 112 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ALBUMART /albumart?cacheid=76&web=radio-tsf/neo/extralarge&path=%2Fmnt%2FINTERNAL%2Fneo.mp3&metadata=false Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: URI /mnt/INTERNAL/neo.mp3 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 2 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::saveQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushQueue Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::updateTrackBlock Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrackBlock Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPlay Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: UNSET VOLATILE Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/neo.mp3 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 3 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 2 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: 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":"couleur 3","artist":"radio-tsf","album":"couleur 3","uri":"INTERNAL/couleur 3.mp3","trackType":"mp3"} Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: No code Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 45ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 36 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 36 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/neo.mp3" Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 42ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 7ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "INTERNAL/neo.mp3" took 5 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 3ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 7ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 6 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 2ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 22 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 24 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 6 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 3 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"neo","artist":"radio-tsf","album":"neo","uri":"INTERNAL/neo.mp3","trackType":"mp3"} Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"neo","artist":"radio-tsf","album":"neo","uri":"INTERNAL/neo.mp3","trackType":"mp3"} Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 70ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 69ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"neo","artist":"radio-tsf","album":"neo","uri":"INTERNAL/neo.mp3","trackType":"mp3"} Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"neo","artist":"radio-tsf","album":"neo","uri":"INTERNAL/neo.mp3","trackType":"mp3"} Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 88ms Aug 30 15:21:47 tsf-phillips-1956 volumio[745]: info: ------------------------------ 85ms Aug 30 15:21:49 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:49 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 3 milliseconds Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 1 milliseconds Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 0 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 0 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService stop Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::play index undefined Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::startPlaybackTimer Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: [1725031314144] ControllerWebradio::clearAddPlayTrack Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ------------------------------ 32ms Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ------------------------------ 30ms Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: sendMpdCommand stop took 12 milliseconds Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: sendMpdCommand clear took 11 milliseconds Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand load "https://ice.creacast.com/radio-neo-paris" Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ------------------------------ 6ms Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand add "https://ice.creacast.com/radio-neo-paris" Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: error: updateQueue error: null Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ------------------------------ 563ms Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: sendMpdCommand add "https://ice.creacast.com/radio-neo-paris" took 2 milliseconds Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand play Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ------------------------------ 4ms Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: sendMpdCommand play took 4 milliseconds Aug 30 15:21:54 tsf-phillips-1956 volumio[745]: info: ------------------------------ 3ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 21 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 5 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 4 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio-neo-paris","artist":null,"album":null,"uri":"https://ice.creacast.com/radio-neo-paris","trackType":""} Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus stop Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio-neo-paris","artist":null,"album":null,"uri":"https://ice.creacast.com/radio-neo-paris","trackType":""} Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 80ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 80ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 50 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ISAAC ROUX - THE RIGHT PLACE","artist":null,"album":null,"uri":"https://ice.creacast.com/radio-neo-paris","trackType":""} Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ISAAC ROUX - THE RIGHT PLACE","artist":null,"album":null,"uri":"https://ice.creacast.com/radio-neo-paris","trackType":""} Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 114ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 113ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces system playlist update Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Ignoring MPD Status Update Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: ---------------------------- MPD announces state update: player Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::getState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand status Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 90ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 89 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 5ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand status took 3 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: ControllerMpd::parseTrackInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":997,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ISAAC ROUX - THE RIGHT PLACE","artist":null,"album":null,"uri":"https://ice.creacast.com/radio-neo-paris","trackType":""} Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ControllerMpd::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::servicePushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CorePlayQueue::getTrack 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: STATE SERVICE {"status":"play","position":0,"seek":997,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ISAAC ROUX - THE RIGHT PLACE","artist":null,"album":null,"uri":"https://ice.creacast.com/radio-neo-paris","trackType":""} Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: verbose: CURRENT POSITION 1 Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState stateService play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::syncState currentStatus play Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: Received an update from plugin. extracting info from payload Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreStateMachine::pushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::volumioPushState Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 128ms Aug 30 15:21:55 tsf-phillips-1956 volumio[745]: info: ------------------------------ 43ms Aug 30 15:22:02 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 15:22:02 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Enabling plugin audiophonicsonoff Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Loading plugin "audiophonicsonoff"... Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Applying required configuration parameters for plugin audiophonicsonoff Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Audiophonics on/off initiated Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: PLUGIN START: audiophonicsonoff Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Configuring GPIO pins Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Hardware button GPIO binding... OK Aug 30 15:22:04 tsf-phillips-1956 volumio[745]: info: Done. Aug 30 15:22:05 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 15:22:05 tsf-phillips-1956 volumio[745]: info: Loaded the previous config. Aug 30 15:22:05 tsf-phillips-1956 volumio[745]: info: ## populating UI... Aug 30 15:22:05 tsf-phillips-1956 volumio[745]: info: 1/1 settings loaded Aug 30 15:22:05 tsf-phillips-1956 volumio[745]: info: Populated config screen. Aug 30 15:22:07 tsf-phillips-1956 volumio[745]: info: CALLMETHOD: system_controller audiophonicsonoff updateButtonConfig [object Object] Aug 30 15:22:07 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Aug 30 15:22:38 tsf-phillips-1956 volumio[745]: info: CALLMETHOD: system_controller audiophonicsonoff updateButtonConfig [object Object] Aug 30 15:22:38 tsf-phillips-1956 volumio[745]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: info: PLUGIN onShutdown : networkfs Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: info: PLUGIN onShutdown : audiophonicsonoff Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: TypeError: Cannot read property 'writeSync' of undefined Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1281:22) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at ControllerAudiophonicsOnOff.hardShutdownRequest (/data/plugins/system_controller/audiophonicsonoff/index.js:197:21) Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at /data/plugins/system_controller/audiophonicsonoff/node_modules/onoff/onoff.js:135:9 Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: at Array.forEach () Aug 30 15:22:59 tsf-phillips-1956 volumio[745]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 15:22:59 tsf-phillips-1956 sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 15:21 Aug 30 15:22:59 tsf-phillips-1956 sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="9eadfb08439e8c37bef9a38c0641d736c1391e0c" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="1cdccc4d006cc26abc6312edbeae58f3081200d4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Feb 2022 07:16:13 PM CET" VOLUMIO_VERSION="3.233" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="88d67d58bfcedcd908dd7b955a1fa5bd"