-- Logs begin at Sat 2024-04-06 15:04:59 CEST, end at Mon 2024-04-08 21:58:19 CEST. -- Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::play index 8 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand stop took 53 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3 Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 48 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand stop took 35 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 22 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 14 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand clear took 44 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 41 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3" Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:07 volumio20 volumio[1130]: 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":"Track 10","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/10 - Unknown Artist - Track 10.mp3","trackType":"mp3"} Apr 08 21:57:07 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:07 volumio20 volumio[1130]: info: No code Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:07 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:07 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 259ms Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 215ms Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 205ms Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:07 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 205ms Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3" took 189 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 66ms Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 64ms Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:07 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 137ms Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand play took 117 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 114ms Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 108ms Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 52 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 53 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 41 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 32 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 136 milliseconds Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 138 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 134 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 128 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand status took 87 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand status took 76 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 462ms Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 601ms Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 584ms Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 578ms Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 362 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 365 milliseconds Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 730ms Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 728ms Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:09 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:09 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","service":"mpd","name":"9 - Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Unknown%20Artist/Pocity%20s%20hudbou/extralarge&path=%2FINTERNAL%2FRelax%2FVA%20-%20Pocity%20s%20hudbou&metadata=false","duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:09 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:09 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:09 volumio20 volumio[1130]: error: null Apr 08 21:57:09 volumio20 volumio[1130]: info: Apr 08 21:57:09 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:09 volumio20 volumio[1130]: info: Apr 08 21:57:09 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:09 volumio20 volumio[1130]: info: Apr 08 21:57:09 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:09 volumio20 volumio[1130]: info: sendMpdCommand status took 65 milliseconds Apr 08 21:57:09 volumio20 volumio[1130]: info: sendMpdCommand status took 57 milliseconds Apr 08 21:57:09 volumio20 volumio[1130]: info: sendMpdCommand status took 51 milliseconds Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:10 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 08 21:57:10 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 08 21:57:10 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:10 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133000,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:10 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:10 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:10 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133000,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:10 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:10 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:10 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133000,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:10 volumio20 volumio[1130]: verbose: CURRENT POSITION 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:10 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:10 volumio20 volumio[1130]: info: ------------------------------ 539ms Apr 08 21:57:10 volumio20 volumio[1130]: info: ------------------------------ 538ms Apr 08 21:57:10 volumio20 volumio[1130]: info: ------------------------------ 533ms Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::play index 7 Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8 Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:57:14 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:14 volumio20 volumio[1130]: info: Apr 08 21:57:14 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:14 volumio20 volumio[1130]: info: sendMpdCommand stop took 41 milliseconds Apr 08 21:57:14 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3 Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 73 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand stop took 59 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 23 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 16 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand clear took 48 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 46 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3" Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: 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":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"} Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: info: No code Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:15 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 269ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 214ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 205ms Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:15 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 198ms Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3" took 182 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 61ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 54ms Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:15 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 169ms Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand play took 144 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 140ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 126ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 58 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 45 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 37 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 102 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 76 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 76 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 100 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 88 milliseconds Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:15 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:15 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 431ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 496ms Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 498ms Apr 08 21:57:16 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 367 milliseconds Apr 08 21:57:16 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 358 milliseconds Apr 08 21:57:16 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 359 milliseconds Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:16 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:16 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:16 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:16 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:16 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:16 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: info: ------------------------------ 930ms Apr 08 21:57:16 volumio20 volumio[1130]: info: ------------------------------ 878ms Apr 08 21:57:16 volumio20 volumio[1130]: info: ------------------------------ 866ms Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","service":"mpd","name":"8 - Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Unknown%20Artist/Pocity%20s%20hudbou/extralarge&path=%2FINTERNAL%2FRelax%2FVA%20-%20Pocity%20s%20hudbou&metadata=false","duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:16 volumio20 volumio[1130]: error: null Apr 08 21:57:16 volumio20 volumio[1130]: info: Apr 08 21:57:16 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:16 volumio20 volumio[1130]: info: Apr 08 21:57:16 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:16 volumio20 volumio[1130]: info: Apr 08 21:57:16 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand status took 82 milliseconds Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand status took 67 milliseconds Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand status took 64 milliseconds Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 163 milliseconds Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 162 milliseconds Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 163 milliseconds Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:17 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:17 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:17 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:17 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:17 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:17 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:17 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:17 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:17 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:17 volumio20 volumio[1130]: info: ------------------------------ 654ms Apr 08 21:57:17 volumio20 volumio[1130]: info: ------------------------------ 645ms Apr 08 21:57:17 volumio20 volumio[1130]: info: ------------------------------ 643ms Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioClearQueue Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue Apr 08 21:57:21 volumio20 volumio[1130]: info: Apr 08 21:57:21 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand stop took 100 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:21 volumio20 volumio[1130]: info: Apr 08 21:57:21 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:21 volumio20 volumio[1130]: info: Apr 08 21:57:21 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand status took 38 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand status took 21 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand status took 11 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 62 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 63 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 65 milliseconds Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: 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":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:21 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: No code Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: 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":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:21 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: No code Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: 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":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"} Apr 08 21:57:21 volumio20 volumio[1130]: verbose: CURRENT POSITION 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: No code Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7 Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:21 volumio20 volumio[1130]: info: ------------------------------ 443ms Apr 08 21:57:21 volumio20 volumio[1130]: info: ------------------------------ 425ms Apr 08 21:57:21 volumio20 volumio[1130]: info: ------------------------------ 419ms Apr 08 21:57:32 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 21:57:32 volumio20 volumio[1130]: info: CURURI: music-library/INTERNAL/Meditácia Apr 08 21:57:32 volumio20 volumio[1130]: info: Preload queue cleared Apr 08 21:57:32 volumio20 volumio[1130]: info: Preloading song: music-library/INTERNAL/Meditácia/Best Meditation Music .. Oliver Shanti Vol. I.mp3 Apr 08 21:57:32 volumio20 volumio[1130]: info: Preloading song: music-library/INTERNAL/Meditácia/RELAX- Meditation and Sleep Music.mp3 Apr 08 21:57:32 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/Best Meditation Music .. Oliver Shanti Vol. I.mp3 in service mpd Apr 08 21:57:32 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FBest%20Meditation%20Music%20..%20Oliver%20Shanti%20Vol.%20I.mp3&metadata=false Apr 08 21:57:32 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Best Meditation Music .. Oliver Shanti Vol. I.mp3 Apr 08 21:57:32 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/RELAX- Meditation and Sleep Music.mp3 in service mpd Apr 08 21:57:32 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FRELAX-%20Meditation%20and%20Sleep%20Music.mp3&metadata=false Apr 08 21:57:32 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/RELAX- Meditation and Sleep Music.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::addQueueItems Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::addQueueItems Apr 08 21:57:39 volumio20 volumio[1130]: info: Preload queue cleared Apr 08 21:57:39 volumio20 volumio[1130]: info: Adding Item to queue: music-library/INTERNAL/Meditácia/Celtic Meditation Music Apr 08 21:57:39 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/Celtic Meditation Music in service mpd Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F01%20-%20Blind%20Mary.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F02%20-%20Aran%20Boat%20Song.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F03%20-%20Far%20Beyond%20Yon%20Mountain.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F04%20-%20Were%20You%20At%20The%20Rock.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F05%20-%20At%20The%20Edge%20Of%20The%20White%20Rock.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/05 - At The Edge Of The White Rock.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F06%20-%20Dark%20Island.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/06 - Dark Island.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F07%20-%20An%20Bhoutals.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/07 - An Bhoutals.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F08%20-%20Gentle%20Maiden.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/08 - Gentle Maiden.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F09%20-%20Dawning%20Of%20The%20Day.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/09 - Dawning Of The Day.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F10%20-%20Easter%20Snow.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F11%20-%20Limericks%20Lamentation.mp3&metadata=false Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/11 - Limericks Lamentation.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::play index 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3 Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand stop took 8 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand clear took 26 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3" Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:39 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 46ms Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3" took 40 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 35ms Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 30ms Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:39 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 86ms Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand play took 72 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 70ms Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 51ms Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 100 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 98 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 85 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:39 volumio20 volumio[1130]: info: Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 138 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 66 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 64 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 71 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 54 milliseconds Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:39 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:39 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:39 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:39 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:39 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 448ms Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 547ms Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 521ms Apr 08 21:57:40 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 334 milliseconds Apr 08 21:57:40 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 327 milliseconds Apr 08 21:57:40 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:40 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:40 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:40 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:40 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:40 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:40 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:40 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:40 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:40 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 846ms Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 739ms Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","service":"mpd","name":"1 - Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:43 volumio20 volumio[1130]: error: null Apr 08 21:57:43 volumio20 volumio[1130]: info: Apr 08 21:57:43 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:43 volumio20 volumio[1130]: info: Apr 08 21:57:43 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:43 volumio20 volumio[1130]: info: Apr 08 21:57:43 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand status took 50 milliseconds Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand status took 38 milliseconds Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand status took 30 milliseconds Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 39 milliseconds Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 41 milliseconds Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:43 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:43 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:43 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:43 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:43 volumio20 volumio[1130]: verbose: CURRENT POSITION 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:43 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:44 volumio20 volumio[1130]: info: ------------------------------ 785ms Apr 08 21:57:44 volumio20 volumio[1130]: info: ------------------------------ 797ms Apr 08 21:57:44 volumio20 volumio[1130]: info: ------------------------------ 797ms Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioNext Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::next Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand stop took 70 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3 Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 63 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand stop took 49 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 35 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 28 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand clear took 23 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3" Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:46 volumio20 volumio[1130]: 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":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"} Apr 08 21:57:46 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:46 volumio20 volumio[1130]: info: No code Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:46 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:46 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 223ms Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 190ms Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 183ms Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:46 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 162ms Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3" took 149 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 47ms Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 41ms Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:46 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 138ms Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand play took 119 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 116ms Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 107ms Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 52 milliseconds Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 61 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 73 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 68 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:47 volumio20 volumio[1130]: info: Apr 08 21:57:47 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:47 volumio20 volumio[1130]: info: Apr 08 21:57:47 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 96 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 101 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 97 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 96 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 81 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 73 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 512ms Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 765ms Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 744ms Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 739ms Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 543 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 543 milliseconds Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 947ms Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 945ms Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","service":"mpd","name":"2 - Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: error: null Apr 08 21:57:48 volumio20 volumio[1130]: info: Apr 08 21:57:48 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:48 volumio20 volumio[1130]: info: Apr 08 21:57:48 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:48 volumio20 volumio[1130]: info: Apr 08 21:57:48 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand status took 124 milliseconds Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand status took 63 milliseconds Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand status took 49 milliseconds Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 145 milliseconds Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 145 milliseconds Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 141 milliseconds Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:48 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:48 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:48 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:48 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:48 volumio20 volumio[1130]: verbose: CURRENT POSITION 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:48 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:48 volumio20 volumio[1130]: info: ------------------------------ 679ms Apr 08 21:57:49 volumio20 volumio[1130]: info: ------------------------------ 616ms Apr 08 21:57:49 volumio20 volumio[1130]: info: ------------------------------ 608ms Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioNext Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::next Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand stop took 40 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3 Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand status took 58 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand stop took 44 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand status took 33 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand status took 26 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand clear took 34 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3" Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:52 volumio20 volumio[1130]: 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":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"} Apr 08 21:57:52 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:52 volumio20 volumio[1130]: info: No code Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:52 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:52 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 222ms Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 190ms Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 182ms Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:52 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:57:52 volumio20 volumio[1130]: info: Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 173ms Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3" took 170 milliseconds Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 68ms Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 63ms Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:53 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:53 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:53 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 131ms Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand play took 106 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 103ms Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 83ms Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 45 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 37 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 76 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 69 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 57 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 50 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:53 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 278ms Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 299ms Apr 08 21:57:53 volumio20 volumio[1130]: info: Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 290 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 258 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 256 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 72 milliseconds Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:53 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:53 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 609ms Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 604ms Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 473 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 471 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":537,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: ------------------------------ 1072ms Apr 08 21:57:54 volumio20 volumio[1130]: info: ------------------------------ 839ms Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","service":"mpd","name":"3 - Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: error: null Apr 08 21:57:54 volumio20 volumio[1130]: info: Apr 08 21:57:54 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:54 volumio20 volumio[1130]: info: Apr 08 21:57:54 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:54 volumio20 volumio[1130]: info: Apr 08 21:57:54 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand status took 149 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand status took 38 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand status took 28 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:54 volumio20 volumio[1130]: info: ------------------------------ 545ms Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 466ms Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 457ms Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioNext Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::next Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand stop took 62 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3 Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand status took 65 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand stop took 52 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand status took 41 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand status took 36 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand clear took 34 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3" Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:55 volumio20 volumio[1130]: 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":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"} Apr 08 21:57:55 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:55 volumio20 volumio[1130]: info: No code Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:55 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:55 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 246ms Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 215ms Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 208ms Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:55 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 209ms Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3" took 195 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 68ms Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 61ms Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:55 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 171ms Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand play took 171 milliseconds Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 173ms Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 172ms Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:55 volumio20 volumio[1130]: info: Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 284 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 268 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 254 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 245 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:56 volumio20 volumio[1130]: info: Apr 08 21:57:56 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 78 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 74 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 63 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 35 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 738ms Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 886ms Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 842ms Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 833ms Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","service":"mpd","name":"4 - Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: error: null Apr 08 21:57:56 volumio20 volumio[1130]: info: Apr 08 21:57:56 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 584 milliseconds Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 782ms Apr 08 21:57:57 volumio20 volumio[1130]: info: Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:57 volumio20 volumio[1130]: info: Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand status took 370 milliseconds Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand status took 56 milliseconds Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand status took 47 milliseconds Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","service":"mpd","name":"4 - Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: error: null Apr 08 21:57:57 volumio20 volumio[1130]: info: Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 292 milliseconds Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 293 milliseconds Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 288 milliseconds Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264000,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:57 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:57 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264879,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:57 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:57 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264879,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:57 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:57 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:57 volumio20 volumio[1130]: info: ------------------------------ 1035ms Apr 08 21:57:57 volumio20 volumio[1130]: info: ------------------------------ 721ms Apr 08 21:57:57 volumio20 volumio[1130]: info: ------------------------------ 719ms Apr 08 21:57:57 volumio20 volumio[1130]: info: Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:57 volumio20 volumio[1130]: info: Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand status took 529 milliseconds Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand status took 58 milliseconds Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand status took 50 milliseconds Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 188 milliseconds Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 187 milliseconds Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 186 milliseconds Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:57:58 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330879,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:58 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:58 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:58 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331382,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:58 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:58 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:58 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331382,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:57:58 volumio20 volumio[1130]: verbose: CURRENT POSITION 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:57:58 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:57:58 volumio20 volumio[1130]: info: ------------------------------ 1142ms Apr 08 21:57:58 volumio20 volumio[1130]: info: ------------------------------ 695ms Apr 08 21:57:58 volumio20 volumio[1130]: info: ------------------------------ 660ms Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::play index 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:58:02 volumio20 volumio[1130]: info: Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand stop took 60 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3 Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand status took 20 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand stop took 7 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 21:58:02 volumio20 volumio[1130]: info: Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:02 volumio20 volumio[1130]: info: Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:02 volumio20 volumio[1130]: info: Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:58:02 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 50 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand clear took 50 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand status took 40 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand status took 31 milliseconds Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3" Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: 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":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"} Apr 08 21:58:02 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: No code Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: 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} Apr 08 21:58:02 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: No code Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: 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} Apr 08 21:58:02 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: No code Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:02 volumio20 volumio[1130]: info: ------------------------------ 376ms Apr 08 21:58:02 volumio20 volumio[1130]: info: ------------------------------ 336ms Apr 08 21:58:02 volumio20 volumio[1130]: info: ------------------------------ 333ms Apr 08 21:58:02 volumio20 volumio[1130]: info: Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:58:02 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:58:02 volumio20 volumio[1130]: info: Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:58:02 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:58:03 volumio20 volumio[1130]: error: updateQueue error: null Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:58:03 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 369ms Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3" took 352 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 59ms Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 53ms Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:58:03 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update Apr 08 21:58:03 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 08 21:58:03 volumio20 volumio[1130]: info: Ignoring MPD Status Update Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 209ms Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand play took 197 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 196ms Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 203ms Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 140 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 147 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 136 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 111 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:03 volumio20 volumio[1130]: info: Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 169 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 172 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 169 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 170 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 129 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 121 milliseconds Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:03 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:03 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:03 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 595ms Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 733ms Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 697ms Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 702ms Apr 08 21:58:04 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 425 milliseconds Apr 08 21:58:04 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 426 milliseconds Apr 08 21:58:04 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:04 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:04 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:04 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:04 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:04 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:04 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:04 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:04 volumio20 volumio[1130]: info: ------------------------------ 965ms Apr 08 21:58:04 volumio20 volumio[1130]: info: ------------------------------ 964ms Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::seek Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","service":"mpd","name":"10 - Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: ControllerMpd::seek Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: error: null Apr 08 21:58:05 volumio20 volumio[1130]: info: Apr 08 21:58:05 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:05 volumio20 volumio[1130]: info: Apr 08 21:58:05 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:05 volumio20 volumio[1130]: info: Apr 08 21:58:05 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand status took 133 milliseconds Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand status took 119 milliseconds Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand status took 107 milliseconds Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 37 milliseconds Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:05 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:05 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:05 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:05 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:05 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Apr 08 21:58:05 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:05 volumio20 volumio[1130]: info: ------------------------------ 651ms Apr 08 21:58:05 volumio20 volumio[1130]: info: ------------------------------ 640ms Apr 08 21:58:05 volumio20 volumio[1130]: info: ------------------------------ 643ms Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioClearQueue Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop Apr 08 21:58:07 volumio20 volumio[1130]: info: ControllerMpd::stop Apr 08 21:58:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue Apr 08 21:58:07 volumio20 volumio[1130]: info: Apr 08 21:58:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:07 volumio20 volumio[1130]: info: sendMpdCommand stop took 131 milliseconds Apr 08 21:58:07 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:07 volumio20 volumio[1130]: info: Apr 08 21:58:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:08 volumio20 volumio[1130]: info: Apr 08 21:58:08 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::getState Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand status took 41 milliseconds Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand status took 34 milliseconds Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand status took 28 milliseconds Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: 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":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: No code Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: 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":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: No code Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: 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":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"} Apr 08 21:58:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: No code Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9 Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState Apr 08 21:58:08 volumio20 volumio[1130]: info: ------------------------------ 386ms Apr 08 21:58:08 volumio20 volumio[1130]: info: ------------------------------ 381ms Apr 08 21:58:08 volumio20 volumio[1130]: info: ------------------------------ 379ms Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::stop Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 21:58:16 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue Apr 08 21:58:16 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::addQueueItems Apr 08 21:58:16 volumio20 volumio[1130]: info: CorePlayQueue::addQueueItems Apr 08 21:58:16 volumio20 volumio[1130]: info: Preload queue cleared Apr 08 21:58:16 volumio20 volumio[1130]: info: Adding Item to queue: music-library/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac) Apr 08 21:58:16 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac) in service mpd Apr 08 21:58:16 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Bandari/Chinese%20Poem/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FBandari%20-%20Chinese%20Poem%20%5B2015%5D%20(flac)%2FBandari%20-%20Chinese%20Poem.cue&metadata=false Apr 08 21:58:16 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac)/Bandari - Chinese Poem.cue Apr 08 21:58:16 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Bandari/Chinese%20Poem/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FBandari%20-%20Chinese%20Poem%20%5B2015%5D%20(flac)%2FBandari%20-%20Chinese%20Poem.cue&metadata=false Apr 08 21:58:16 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac)/Bandari - Chinese Poem.cue Apr 08 21:58:16 volumio20 volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 21:58:16 volumio20 volumio[1130]: Error: Unable to resolve or reject the same promise twice Apr 08 21:58:16 volumio20 volumio[1130]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 08 21:58:16 volumio20 volumio[1130]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 08 21:58:16 volumio20 volumio[1130]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 08 21:58:16 volumio20 volumio[1130]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 08 21:58:16 volumio20 volumio[1130]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 08 21:58:16 volumio20 volumio[1130]: at Socket.emit (events.js:315:20) Apr 08 21:58:16 volumio20 volumio[1130]: at addChunk (internal/streams/readable.js:309:12) Apr 08 21:58:16 volumio20 volumio[1130]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 08 21:58:16 volumio20 volumio[1130]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 08 21:58:16 volumio20 volumio[1130]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 08 21:58:16 volumio20 volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 21:58:19 volumio20 sudo[18781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 21:57 Apr 08 21:58:19 volumio20 sudo[18781]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"