-- Logs begin at Thu 2026-04-02 15:15:45 UTC, end at Thu 2026-04-02 15:46:37 UTC. -- Apr 02 15:45:01 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:01 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:01 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99624894 Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::play index 17 Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 16 Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::serviceStop Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 16 Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Apr 02 15:45:01 volumio volumio[1075]: info: [1775144701572] ControllerWebradio::stop Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand stop took 6 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: [1775144701579] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand stop took 1 milliseconds Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:01 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:01 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:01 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand clear took 3 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99624894" Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:01 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:45:01 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:45:01 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:01 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 17ms Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 13ms Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:01 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:01 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:01 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 14ms Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:01 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99624894" took 108 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 100ms Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 99ms Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:01 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:01 volumio volumio[1075]: info: Apr 02 15:45:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:01 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:01 volumio volumio[1075]: info: sendMpdCommand play took 3 milliseconds Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:01 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive","artist":"(#1 - 1/512) PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive","artist":"(#1 - 1/512) PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive","artist":"(#1 - 1/512) PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive","artist":"(#1 - 1/512) PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 30ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 38ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 38ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 36ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive","artist":"(#1 - 1/512) PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive","artist":"(#1 - 1/512) PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 47ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 46ms Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:02 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:02 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:02 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:02 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 6ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:02 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:02 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:02 volumio volumio[1075]: info: Apr 02 15:45:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 7ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Kaleidor - Fill Your Head with Phantasm, Vol. 10 (Continuous Mix)","artist":"PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Kaleidor - Fill Your Head with Phantasm, Vol. 10 (Continuous Mix)","artist":"PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Kaleidor - Fill Your Head with Phantasm, Vol. 10 (Continuous Mix)","artist":"PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 35ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 34ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 33ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand status took 27 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Kaleidor - Fill Your Head with Phantasm, Vol. 10 (Continuous Mix)","artist":"PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Kaleidor - Fill Your Head with Phantasm, Vol. 10 (Continuous Mix)","artist":"PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 47ms Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 43ms Apr 02 15:45:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 02 15:45:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:02 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Kaleidor - Fill Your Head with Phantasm, Vol. 10 (Continuous Mix)","artist":"PsyBrazil Progressive Radio Station","album":null,"uri":"http://186.236.237.155:8000/progressive","trackType":""} Apr 02 15:45:02 volumio volumio[1075]: verbose: CURRENT POSITION 17 Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:02 volumio volumio[1075]: info: ------------------------------ 56ms Apr 02 15:45:17 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:17 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:17 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99219516 Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::play index 18 Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::serviceStop Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 17 Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Apr 02 15:45:17 volumio volumio[1075]: info: [1775144717131] ControllerWebradio::stop Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand stop took 6 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: info: [1775144717139] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:17 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99219516" Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:45:17 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 12ms Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 10ms Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:17 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:17 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:17 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 8ms Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:17 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 12ms Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:17 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99219516" took 110 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 105ms Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:17 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:17 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand play took 3 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:17 volumio volumio[1075]: info: Apr 02 15:45:17 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand status took 7 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand clearerror took 6 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand clearerror took 2 milliseconds Apr 02 15:45:17 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:45:17 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: 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":"djhellmaker","artist":"DJ HELLMAKER","album":null,"uri":"http://listen.shoutcast.com/djhellmaker","trackType":""} Apr 02 15:45:17 volumio volumio[1075]: verbose: CURRENT POSITION 18 Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: No code Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: 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":"djhellmaker","artist":"DJ HELLMAKER","album":null,"uri":"http://listen.shoutcast.com/djhellmaker","trackType":""} Apr 02 15:45:17 volumio volumio[1075]: verbose: CURRENT POSITION 18 Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: No code Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: CorePlayQueue::getTrack 18 Apr 02 15:45:17 volumio volumio[1075]: 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":"djhellmaker","artist":"DJ HELLMAKER","album":null,"uri":"http://listen.shoutcast.com/djhellmaker","trackType":""} Apr 02 15:45:17 volumio volumio[1075]: verbose: CURRENT POSITION 18 Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: No code Apr 02 15:45:17 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:17 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 36ms Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 36ms Apr 02 15:45:17 volumio volumio[1075]: info: ------------------------------ 36ms Apr 02 15:45:19 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:19 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:19 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99387928 Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::play index 19 Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::getTrack 19 Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::getTrack 19 Apr 02 15:45:19 volumio volumio[1075]: info: [1775144719190] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand stop took 1 milliseconds Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:19 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99387928" Apr 02 15:45:19 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:19 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:19 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99387928" took 103 milliseconds Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:19 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 104ms Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 103ms Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:19 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:19 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand play took 3 milliseconds Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:19 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:19 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:19 volumio volumio[1075]: info: Apr 02 15:45:19 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:19 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand status took 7 milliseconds Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:19 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::getTrack 19 Apr 02 15:45:19 volumio volumio[1075]: 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":"radiocorsario","artist":"Radio Corsario","album":null,"uri":"http://listen.shoutcast.com/radiocorsario","trackType":""} Apr 02 15:45:19 volumio volumio[1075]: verbose: CURRENT POSITION 19 Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: No code Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::getTrack 19 Apr 02 15:45:19 volumio volumio[1075]: 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":"radiocorsario","artist":"Radio Corsario","album":null,"uri":"http://listen.shoutcast.com/radiocorsario","trackType":""} Apr 02 15:45:19 volumio volumio[1075]: verbose: CURRENT POSITION 19 Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: No code Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 33ms Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand clearerror took 30 milliseconds Apr 02 15:45:19 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 02 15:45:19 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:19 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: CorePlayQueue::getTrack 19 Apr 02 15:45:19 volumio volumio[1075]: 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":"radiocorsario","artist":"Radio Corsario","album":null,"uri":"http://listen.shoutcast.com/radiocorsario","trackType":""} Apr 02 15:45:19 volumio volumio[1075]: verbose: CURRENT POSITION 19 Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: No code Apr 02 15:45:19 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:19 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:19 volumio volumio[1075]: info: ------------------------------ 46ms Apr 02 15:45:22 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:22 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:22 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99375180 Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::play index 20 Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::getTrack 20 Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::getTrack 20 Apr 02 15:45:22 volumio volumio[1075]: info: [1775144722304] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:22 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99375180" Apr 02 15:45:22 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:22 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:22 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99375180" took 104 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:22 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 105ms Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:22 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:22 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand play took 3 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:22 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:22 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:22 volumio volumio[1075]: info: Apr 02 15:45:22 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:22 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand status took 7 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand clearerror took 4 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:22 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::getTrack 20 Apr 02 15:45:22 volumio volumio[1075]: 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":"huntershomestead","artist":"Hunters Homestead","album":null,"uri":"http://listen.shoutcast.com/huntershomestead","trackType":""} Apr 02 15:45:22 volumio volumio[1075]: verbose: CURRENT POSITION 20 Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: No code Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::getTrack 20 Apr 02 15:45:22 volumio volumio[1075]: 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":"huntershomestead","artist":"Hunters Homestead","album":null,"uri":"http://listen.shoutcast.com/huntershomestead","trackType":""} Apr 02 15:45:22 volumio volumio[1075]: verbose: CURRENT POSITION 20 Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: No code Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 26ms Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 26ms Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand clearerror took 22 milliseconds Apr 02 15:45:22 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 02 15:45:22 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:22 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: CorePlayQueue::getTrack 20 Apr 02 15:45:22 volumio volumio[1075]: 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":"huntershomestead","artist":"Hunters Homestead","album":null,"uri":"http://listen.shoutcast.com/huntershomestead","trackType":""} Apr 02 15:45:22 volumio volumio[1075]: verbose: CURRENT POSITION 20 Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: No code Apr 02 15:45:22 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:22 volumio volumio[1075]: info: ------------------------------ 38ms Apr 02 15:45:24 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:24 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:24 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99391699 Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::play index 21 Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::getTrack 21 Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::getTrack 21 Apr 02 15:45:24 volumio volumio[1075]: info: [1775144724055] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:24 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99391699" Apr 02 15:45:24 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:24 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:24 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:24 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99391699" took 102 milliseconds Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 102ms Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 101ms Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:24 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:24 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand play took 1 milliseconds Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:24 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:24 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:24 volumio volumio[1075]: info: Apr 02 15:45:24 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:24 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand status took 7 milliseconds Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand clearerror took 4 milliseconds Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:24 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::getTrack 21 Apr 02 15:45:24 volumio volumio[1075]: 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":"gatorsecradio","artist":"Gatorsec Radio","album":null,"uri":"http://listen.shoutcast.com/gatorsecradio","trackType":""} Apr 02 15:45:24 volumio volumio[1075]: verbose: CURRENT POSITION 21 Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: No code Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::getTrack 21 Apr 02 15:45:24 volumio volumio[1075]: 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":"gatorsecradio","artist":"Gatorsec Radio","album":null,"uri":"http://listen.shoutcast.com/gatorsecradio","trackType":""} Apr 02 15:45:24 volumio volumio[1075]: verbose: CURRENT POSITION 21 Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: No code Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 27ms Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 27ms Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand clearerror took 25 milliseconds Apr 02 15:45:24 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 02 15:45:24 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:24 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: CorePlayQueue::getTrack 21 Apr 02 15:45:24 volumio volumio[1075]: 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":"gatorsecradio","artist":"Gatorsec Radio","album":null,"uri":"http://listen.shoutcast.com/gatorsecradio","trackType":""} Apr 02 15:45:24 volumio volumio[1075]: verbose: CURRENT POSITION 21 Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: No code Apr 02 15:45:24 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:24 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:24 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:45:26 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:26 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:26 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99355925 Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::play index 22 Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::getTrack 22 Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::getTrack 22 Apr 02 15:45:26 volumio volumio[1075]: info: [1775144726046] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:26 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99355925" Apr 02 15:45:26 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:26 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:26 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:26 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99355925" took 130 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 127ms Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 126ms Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:26 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:26 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand play took 4 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:26 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:26 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:26 volumio volumio[1075]: info: Apr 02 15:45:26 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:26 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand status took 8 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand clearerror took 6 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:26 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::getTrack 22 Apr 02 15:45:26 volumio volumio[1075]: 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":"sora-ssabbulonarium","artist":"Sora's Sabbulonarium","album":null,"uri":"http://listen.shoutcast.com/sora-ssabbulonarium","trackType":""} Apr 02 15:45:26 volumio volumio[1075]: verbose: CURRENT POSITION 22 Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: No code Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::getTrack 22 Apr 02 15:45:26 volumio volumio[1075]: 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":"sora-ssabbulonarium","artist":"Sora's Sabbulonarium","album":null,"uri":"http://listen.shoutcast.com/sora-ssabbulonarium","trackType":""} Apr 02 15:45:26 volumio volumio[1075]: verbose: CURRENT POSITION 22 Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: No code Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 25ms Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 25ms Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand clearerror took 24 milliseconds Apr 02 15:45:26 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 02 15:45:26 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:26 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: CorePlayQueue::getTrack 22 Apr 02 15:45:26 volumio volumio[1075]: 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":"sora-ssabbulonarium","artist":"Sora's Sabbulonarium","album":null,"uri":"http://listen.shoutcast.com/sora-ssabbulonarium","trackType":""} Apr 02 15:45:26 volumio volumio[1075]: verbose: CURRENT POSITION 22 Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: No code Apr 02 15:45:26 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:26 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:26 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:45:30 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:30 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:30 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:30 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:30 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371196 Apr 02 15:45:30 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:30 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:30 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:30 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:30 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::play index 23 Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:30 volumio volumio[1075]: info: CorePlayQueue::getTrack 23 Apr 02 15:45:30 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:30 volumio volumio[1075]: info: CorePlayQueue::getTrack 23 Apr 02 15:45:30 volumio volumio[1075]: info: [1775144730949] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:30 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:30 volumio volumio[1075]: info: sendMpdCommand stop took 3 milliseconds Apr 02 15:45:30 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:30 volumio volumio[1075]: info: Apr 02 15:45:30 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:30 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:30 volumio volumio[1075]: info: sendMpdCommand clear took 2 milliseconds Apr 02 15:45:30 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371196" Apr 02 15:45:30 volumio volumio[1075]: info: Apr 02 15:45:30 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:30 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:30 volumio volumio[1075]: info: Apr 02 15:45:30 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:30 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:30 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:30 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:45:31 volumio volumio[1075]: info: Apr 02 15:45:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371196" took 103 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 104ms Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 104ms Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:31 volumio volumio[1075]: info: Apr 02 15:45:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:31 volumio volumio[1075]: info: Apr 02 15:45:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand play took 4 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:31 volumio volumio[1075]: info: Apr 02 15:45:31 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:31 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:31 volumio volumio[1075]: info: Apr 02 15:45:31 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:31 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:31 volumio volumio[1075]: info: Apr 02 15:45:31 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:31 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand status took 11 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand clearerror took 8 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand clearerror took 7 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:31 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 23 Apr 02 15:45:31 volumio volumio[1075]: 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":"atlasbd-radio","artist":"Atlas BD - Radio","album":null,"uri":"http://listen.shoutcast.com/atlasbd-radio","trackType":""} Apr 02 15:45:31 volumio volumio[1075]: verbose: CURRENT POSITION 23 Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: No code Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 23 Apr 02 15:45:31 volumio volumio[1075]: 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":"atlasbd-radio","artist":"Atlas BD - Radio","album":null,"uri":"http://listen.shoutcast.com/atlasbd-radio","trackType":""} Apr 02 15:45:31 volumio volumio[1075]: verbose: CURRENT POSITION 23 Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: No code Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 40ms Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 39ms Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand clearerror took 37 milliseconds Apr 02 15:45:31 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 02 15:45:31 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:31 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 23 Apr 02 15:45:31 volumio volumio[1075]: 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":"atlasbd-radio","artist":"Atlas BD - Radio","album":null,"uri":"http://listen.shoutcast.com/atlasbd-radio","trackType":""} Apr 02 15:45:31 volumio volumio[1075]: verbose: CURRENT POSITION 23 Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: No code Apr 02 15:45:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:31 volumio volumio[1075]: info: ------------------------------ 59ms Apr 02 15:45:38 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:38 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:38 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99298848 Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::play index 24 Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 24 Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 24 Apr 02 15:45:38 volumio volumio[1075]: info: [1775144738469] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand stop took 5 milliseconds Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand clear took 0 milliseconds Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99298848" Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:38 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:38 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:38 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:38 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99298848" took 107 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 109ms Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 108ms Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 107ms Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:38 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:38 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand play took 4 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:38 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:38 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:38 volumio volumio[1075]: info: Apr 02 15:45:38 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:38 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand status took 9 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand clearerror took 7 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand clearerror took 6 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:38 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 24 Apr 02 15:45:38 volumio volumio[1075]: 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":"tal-mahe-raradio","artist":"Tal'mahe'Ra Radio","album":null,"uri":"http://listen.shoutcast.com/tal-mahe-raradio","trackType":""} Apr 02 15:45:38 volumio volumio[1075]: verbose: CURRENT POSITION 24 Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: No code Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 24 Apr 02 15:45:38 volumio volumio[1075]: 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":"tal-mahe-raradio","artist":"Tal'mahe'Ra Radio","album":null,"uri":"http://listen.shoutcast.com/tal-mahe-raradio","trackType":""} Apr 02 15:45:38 volumio volumio[1075]: verbose: CURRENT POSITION 24 Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: No code Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 37ms Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 36ms Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand clearerror took 33 milliseconds Apr 02 15:45:38 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 02 15:45:38 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:38 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 24 Apr 02 15:45:38 volumio volumio[1075]: 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":"tal-mahe-raradio","artist":"Tal'mahe'Ra Radio","album":null,"uri":"http://listen.shoutcast.com/tal-mahe-raradio","trackType":""} Apr 02 15:45:38 volumio volumio[1075]: verbose: CURRENT POSITION 24 Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: No code Apr 02 15:45:38 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:38 volumio volumio[1075]: info: ------------------------------ 55ms Apr 02 15:45:39 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:39 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:39 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:39 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:39 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99291564 Apr 02 15:45:39 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:39 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:39 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:39 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:39 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::play index 25 Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:39 volumio volumio[1075]: info: CorePlayQueue::getTrack 25 Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:39 volumio volumio[1075]: info: CorePlayQueue::getTrack 25 Apr 02 15:45:39 volumio volumio[1075]: info: [1775144739758] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:39 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:45:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:39 volumio volumio[1075]: info: Apr 02 15:45:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:39 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:39 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99291564" Apr 02 15:45:39 volumio volumio[1075]: info: Apr 02 15:45:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:39 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:39 volumio volumio[1075]: info: Apr 02 15:45:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:39 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:39 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:39 volumio volumio[1075]: info: ------------------------------ 6ms Apr 02 15:45:39 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99291564" took 105 milliseconds Apr 02 15:45:39 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:39 volumio volumio[1075]: info: Apr 02 15:45:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:39 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:39 volumio volumio[1075]: info: ------------------------------ 104ms Apr 02 15:45:39 volumio volumio[1075]: info: ------------------------------ 104ms Apr 02 15:45:39 volumio volumio[1075]: info: Apr 02 15:45:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:39 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:39 volumio volumio[1075]: info: Apr 02 15:45:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:39 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:39 volumio volumio[1075]: info: sendMpdCommand play took 5 milliseconds Apr 02 15:45:39 volumio volumio[1075]: info: ------------------------------ 5ms Apr 02 15:45:39 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:39 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:40 volumio volumio[1075]: info: Apr 02 15:45:40 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:40 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:40 volumio volumio[1075]: info: Apr 02 15:45:40 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:40 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:40 volumio volumio[1075]: info: Apr 02 15:45:40 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:40 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand status took 11 milliseconds Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand clearerror took 8 milliseconds Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand clearerror took 7 milliseconds Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:40 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: CorePlayQueue::getTrack 25 Apr 02 15:45:40 volumio volumio[1075]: 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":"carcelentown-radio","artist":"CarcelenTown-Radio","album":null,"uri":"http://listen.shoutcast.com/carcelentown-radio","trackType":""} Apr 02 15:45:40 volumio volumio[1075]: verbose: CURRENT POSITION 25 Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: No code Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: CorePlayQueue::getTrack 25 Apr 02 15:45:40 volumio volumio[1075]: 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":"carcelentown-radio","artist":"CarcelenTown-Radio","album":null,"uri":"http://listen.shoutcast.com/carcelentown-radio","trackType":""} Apr 02 15:45:40 volumio volumio[1075]: verbose: CURRENT POSITION 25 Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: No code Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: ------------------------------ 40ms Apr 02 15:45:40 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand clearerror took 41 milliseconds Apr 02 15:45:40 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 39 milliseconds Apr 02 15:45:40 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:40 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: CorePlayQueue::getTrack 25 Apr 02 15:45:40 volumio volumio[1075]: 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":"carcelentown-radio","artist":"CarcelenTown-Radio","album":null,"uri":"http://listen.shoutcast.com/carcelentown-radio","trackType":""} Apr 02 15:45:40 volumio volumio[1075]: verbose: CURRENT POSITION 25 Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: No code Apr 02 15:45:40 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:40 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:40 volumio volumio[1075]: info: ------------------------------ 66ms Apr 02 15:45:41 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:41 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:41 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:41 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:41 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99535512 Apr 02 15:45:41 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:41 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:41 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:41 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:41 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::play index 26 Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:41 volumio volumio[1075]: info: CorePlayQueue::getTrack 26 Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:41 volumio volumio[1075]: info: CorePlayQueue::getTrack 26 Apr 02 15:45:41 volumio volumio[1075]: info: [1775144741885] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:41 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:41 volumio volumio[1075]: info: sendMpdCommand stop took 1 milliseconds Apr 02 15:45:41 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:41 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:41 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99535512" Apr 02 15:45:41 volumio volumio[1075]: info: Apr 02 15:45:41 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:41 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:41 volumio volumio[1075]: info: Apr 02 15:45:41 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:41 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:41 volumio volumio[1075]: info: Apr 02 15:45:41 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:41 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:41 volumio volumio[1075]: info: Apr 02 15:45:41 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:41 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:41 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99535512" took 109 milliseconds Apr 02 15:45:41 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:41 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:41 volumio volumio[1075]: info: ------------------------------ 110ms Apr 02 15:45:41 volumio volumio[1075]: info: ------------------------------ 109ms Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 108ms Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 5ms Apr 02 15:45:42 volumio volumio[1075]: info: Apr 02 15:45:42 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:42 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:42 volumio volumio[1075]: info: Apr 02 15:45:42 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:42 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand play took 5 milliseconds Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:42 volumio volumio[1075]: info: Apr 02 15:45:42 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:42 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:42 volumio volumio[1075]: info: Apr 02 15:45:42 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:42 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:42 volumio volumio[1075]: info: Apr 02 15:45:42 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:42 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand status took 10 milliseconds Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand clearerror took 9 milliseconds Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand clearerror took 8 milliseconds Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:42 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: CorePlayQueue::getTrack 26 Apr 02 15:45:42 volumio volumio[1075]: 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":"stream","artist":"(#1 - 0/50) #BMJ_Radio","album":null,"uri":"https://radio1.bmj-radio.com:8006/stream","trackType":""} Apr 02 15:45:42 volumio volumio[1075]: verbose: CURRENT POSITION 26 Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: No code Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: CorePlayQueue::getTrack 26 Apr 02 15:45:42 volumio volumio[1075]: 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":"stream","artist":"(#1 - 0/50) #BMJ_Radio","album":null,"uri":"https://radio1.bmj-radio.com:8006/stream","trackType":""} Apr 02 15:45:42 volumio volumio[1075]: verbose: CURRENT POSITION 26 Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: No code Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 38ms Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 38ms Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand clearerror took 33 milliseconds Apr 02 15:45:42 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 02 15:45:42 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:42 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: CorePlayQueue::getTrack 26 Apr 02 15:45:42 volumio volumio[1075]: 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":"stream","artist":"(#1 - 0/50) #BMJ_Radio","album":null,"uri":"https://radio1.bmj-radio.com:8006/stream","trackType":""} Apr 02 15:45:42 volumio volumio[1075]: verbose: CURRENT POSITION 26 Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: No code Apr 02 15:45:42 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:42 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:42 volumio volumio[1075]: info: ------------------------------ 52ms Apr 02 15:45:43 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:43 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:43 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:43 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:43 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516739 Apr 02 15:45:43 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:43 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:43 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:43 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:43 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::play index 27 Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:43 volumio volumio[1075]: info: CorePlayQueue::getTrack 27 Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:43 volumio volumio[1075]: info: CorePlayQueue::getTrack 27 Apr 02 15:45:43 volumio volumio[1075]: info: [1775144743409] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:43 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:43 volumio volumio[1075]: info: sendMpdCommand stop took 1 milliseconds Apr 02 15:45:43 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:43 volumio volumio[1075]: info: Apr 02 15:45:43 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:43 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:43 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:43 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516739" Apr 02 15:45:43 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:43 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:43 volumio volumio[1075]: info: Apr 02 15:45:43 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:43 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:43 volumio volumio[1075]: info: Apr 02 15:45:43 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:43 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:43 volumio volumio[1075]: info: Apr 02 15:45:43 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:43 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:43 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516739" took 104 milliseconds Apr 02 15:45:43 volumio volumio[1075]: info: ------------------------------ 104ms Apr 02 15:45:43 volumio volumio[1075]: info: ------------------------------ 103ms Apr 02 15:45:43 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:43 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:43 volumio volumio[1075]: info: Apr 02 15:45:43 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:43 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:43 volumio volumio[1075]: info: Apr 02 15:45:43 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:43 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:43 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:45:43 volumio volumio[1075]: info: sendMpdCommand play took 2 milliseconds Apr 02 15:45:43 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:43 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:44 volumio volumio[1075]: info: Apr 02 15:45:44 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:44 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:44 volumio volumio[1075]: info: Apr 02 15:45:44 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:44 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:44 volumio volumio[1075]: info: Apr 02 15:45:44 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:44 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand status took 7 milliseconds Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand clearerror took 5 milliseconds Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:44 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: CorePlayQueue::getTrack 27 Apr 02 15:45:44 volumio volumio[1075]: 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":"stream","artist":"(#1 - 0/50) mousiko katafigio","album":null,"uri":"https://i2.streams.ovh:7211/stream","trackType":""} Apr 02 15:45:44 volumio volumio[1075]: verbose: CURRENT POSITION 27 Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: No code Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: CorePlayQueue::getTrack 27 Apr 02 15:45:44 volumio volumio[1075]: 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":"stream","artist":"(#1 - 0/50) mousiko katafigio","album":null,"uri":"https://i2.streams.ovh:7211/stream","trackType":""} Apr 02 15:45:44 volumio volumio[1075]: verbose: CURRENT POSITION 27 Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: No code Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: ------------------------------ 26ms Apr 02 15:45:44 volumio volumio[1075]: info: ------------------------------ 26ms Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand clearerror took 23 milliseconds Apr 02 15:45:44 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 02 15:45:44 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:44 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: CorePlayQueue::getTrack 27 Apr 02 15:45:44 volumio volumio[1075]: 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":"stream","artist":"(#1 - 0/50) mousiko katafigio","album":null,"uri":"https://i2.streams.ovh:7211/stream","trackType":""} Apr 02 15:45:44 volumio volumio[1075]: verbose: CURRENT POSITION 27 Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::syncState stateService stop Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: No code Apr 02 15:45:44 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:44 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:44 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:45:47 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:47 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:45:47 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:45:47 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:45:47 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1830968 Apr 02 15:45:47 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:45:47 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:45:47 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:45:47 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:45:47 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::play index 28 Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:45:47 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:45:47 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:47 volumio volumio[1075]: info: [1775144747872] ControllerWebradio::clearAddPlayTrack Apr 02 15:45:47 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:45:47 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:45:47 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:45:47 volumio volumio[1075]: info: Apr 02 15:45:47 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:47 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:47 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Apr 02 15:45:47 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1830968" Apr 02 15:45:47 volumio volumio[1075]: info: Apr 02 15:45:47 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:47 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:47 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:45:47 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:47 volumio volumio[1075]: info: Apr 02 15:45:47 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:47 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:47 volumio volumio[1075]: info: Apr 02 15:45:47 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:47 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:47 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1830968" took 106 milliseconds Apr 02 15:45:47 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:45:47 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:45:47 volumio volumio[1075]: info: ------------------------------ 107ms Apr 02 15:45:47 volumio volumio[1075]: info: ------------------------------ 106ms Apr 02 15:45:47 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:47 volumio volumio[1075]: info: Apr 02 15:45:47 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:47 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:47 volumio volumio[1075]: info: Apr 02 15:45:47 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:47 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:47 volumio volumio[1075]: info: sendMpdCommand play took 3 milliseconds Apr 02 15:45:47 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:45:47 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"psytrance_64a","artist":"1.FM - BOM Psytrance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"psytrance_64a","artist":"1.FM - BOM Psytrance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"psytrance_64a","artist":"1.FM - BOM Psytrance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"psytrance_64a","artist":"1.FM - BOM Psytrance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 21ms Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 26ms Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 25ms Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 23ms Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 19 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"psytrance_64a","artist":"1.FM - BOM Psytrance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 28ms Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"psytrance_64a","artist":"1.FM - BOM Psytrance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:49 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:49 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:45:49 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:45:49 volumio volumio[1075]: info: Apr 02 15:45:49 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 5ms Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ilai - Illuminate","artist":"1.FM - BOM Psytrance Radio","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ilai - Illuminate","artist":"1.FM - BOM Psytrance Radio","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 19ms Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 19ms Apr 02 15:45:49 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 02 15:45:49 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:45:49 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:45:49 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:45:49 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ilai - Illuminate","artist":"1.FM - BOM Psytrance Radio","album":null,"uri":"http://185.33.21.112:80/psytrance_64a","trackType":""} Apr 02 15:45:49 volumio volumio[1075]: verbose: CURRENT POSITION 28 Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:45:49 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:45:49 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:45:49 volumio volumio[1075]: info: ------------------------------ 25ms Apr 02 15:46:05 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 02 15:46:05 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:46:07 volumio ntpd[948]: 49.12.35.6 local addr 192.168.178.6 -> Apr 02 15:46:07 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 02 15:46:08 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:46:31 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:46:31 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:46:31 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=47475 Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::play index 29 Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::serviceStop Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 28 Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Apr 02 15:46:31 volumio volumio[1075]: info: [1775144791744] ControllerWebradio::stop Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand stop took 4 milliseconds Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:31 volumio volumio[1075]: info: [1775144791750] ControllerWebradio::clearAddPlayTrack Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:31 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand stop took 1 milliseconds Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:31 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:31 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand clear took 3 milliseconds Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=47475" Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:31 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:31 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:46:31 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:31 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:31 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:31 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 11ms Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 10ms Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:31 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 12ms Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:31 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 16ms Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=47475" took 109 milliseconds Apr 02 15:46:31 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:46:31 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 103ms Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 103ms Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:31 volumio volumio[1075]: info: Apr 02 15:46:31 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:31 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:31 volumio volumio[1075]: info: sendMpdCommand play took 6 milliseconds Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 6ms Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:46:31 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8.43.48.6:8130","artist":"(#1 - 20/1000) ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8.43.48.6:8130","artist":"(#1 - 20/1000) ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 22ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 21ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8.43.48.6:8130","artist":"(#1 - 20/1000) ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8.43.48.6:8130","artist":"(#1 - 20/1000) ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8.43.48.6:8130","artist":"(#1 - 20/1000) ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8.43.48.6:8130","artist":"(#1 - 20/1000) ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 52ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 51ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 49ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 48ms Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:32 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:32 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:32 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:32 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 8ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:32 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:32 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:32 volumio volumio[1075]: info: Apr 02 15:46:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 5ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"E&G pres. Euphoric Sessions 075 (2014-04-09) on ETN.fm","artist":"ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"E&G pres. Euphoric Sessions 075 (2014-04-09) on ETN.fm","artist":"ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 26ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 22ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 18ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand status took 18 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"E&G pres. Euphoric Sessions 075 (2014-04-09) on ETN.fm","artist":"ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"E&G pres. Euphoric Sessions 075 (2014-04-09) on ETN.fm","artist":"ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"E&G pres. Euphoric Sessions 075 (2014-04-09) on ETN.fm","artist":"ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 45ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 45ms Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 42ms Apr 02 15:46:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 02 15:46:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:32 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:32 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"E&G pres. Euphoric Sessions 075 (2014-04-09) on ETN.fm","artist":"ETN.fm Ch1 | EDM Livesets & DJ Shows","album":null,"uri":"http://8.43.48.6:8130","trackType":"6:8130"} Apr 02 15:46:32 volumio volumio[1075]: verbose: CURRENT POSITION 29 Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:32 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:32 volumio volumio[1075]: info: ------------------------------ 57ms Apr 02 15:46:35 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::volumioAddQueueItems Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Apr 02 15:46:35 volumio volumio[1075]: info: Preload queue cleared Apr 02 15:46:35 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::saveQueue Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::play index 30 Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::stop Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::serviceStop Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 29 Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Apr 02 15:46:35 volumio volumio[1075]: info: [1775144795032] ControllerWebradio::stop Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand stop took 5 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::play index undefined Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:35 volumio volumio[1075]: info: [1775144795038] ControllerWebradio::clearAddPlayTrack Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:35 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:35 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:35 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand clear took 2 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:35 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:35 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:35 volumio volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 02 15:46:35 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 13ms Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 12ms Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:35 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:35 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:35 volumio volumio[1075]: error: updateQueue error: null Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 11ms Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:35 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 14ms Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:35 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 115 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 107ms Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 106ms Apr 02 15:46:35 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 15:46:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 3ms Apr 02 15:46:35 volumio volumio[1075]: info: sendMpdCommand play took 1 milliseconds Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:35 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:35 volumio volumio[1075]: info: Apr 02 15:46:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:35 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 2ms Apr 02 15:46:35 volumio volumio[1075]: info: ------------------------------ 1ms Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8037/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 7ms Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8037/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8037/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8037/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 25ms Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 25ms Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 23ms Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:36 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8037/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8037/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 35ms Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 34ms Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:36 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:36 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:36 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 23ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 22 milliseconds Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:36 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 7ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 7ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 6ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 5 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 4ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 35ms Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Apr 02 15:46:36 volumio volumio[1075]: info: Ignoring MPD Status Update Apr 02 15:46:36 volumio volumio[1075]: info: Apr 02 15:46:36 volumio volumio[1075]: ---------------------------- MPD announces state update: player Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::getState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 7ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand status took 6 milliseconds Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseState Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 41ms Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 39ms Apr 02 15:46:36 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 02 15:46:36 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Apr 02 15:46:36 volumio volumio[1075]: info: ControllerMpd::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Apr 02 15:46:36 volumio volumio[1075]: info: CorePlayQueue::getTrack 30 Apr 02 15:46:36 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 02 15:46:36 volumio volumio[1075]: verbose: CURRENT POSITION 30 Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Apr 02 15:46:36 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreStateMachine::pushState Apr 02 15:46:36 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Apr 02 15:46:36 volumio volumio[1075]: info: ------------------------------ 45ms Apr 02 15:46:37 volumio volumio[1075]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 15:46:37 volumio volumio[1075]: TypeError: Cannot read property 'slice' of null Apr 02 15:46:37 volumio volumio[1075]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 02 15:46:37 volumio volumio[1075]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 02 15:46:37 volumio volumio[1075]: at Object.onceWrapper (events.js:520:26) Apr 02 15:46:37 volumio volumio[1075]: at ClientRequest.emit (events.js:400:28) Apr 02 15:46:37 volumio volumio[1075]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Apr 02 15:46:37 volumio volumio[1075]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Apr 02 15:46:37 volumio volumio[1075]: at TLSSocket.socketOnData (_http_client.js:515:22) Apr 02 15:46:37 volumio volumio[1075]: at TLSSocket.emit (events.js:400:28) Apr 02 15:46:37 volumio volumio[1075]: at addChunk (internal/streams/readable.js:293:12) Apr 02 15:46:37 volumio volumio[1075]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 02 15:46:37 volumio volumio[1075]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Apr 02 15:46:37 volumio volumio[1075]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 02 15:46:37 volumio volumio[1075]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 15:46:37 volumio sudo[10077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 15:45 Apr 02 15:46:37 volumio sudo[10077]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"