-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Wed 2025-04-30 06:59:34 CST. -- Apr 30 06:58:06 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:58:06 volumio volumio[1780]: info: [1745967486299] ControllerWebradio::stop Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:58:06 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:06 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1742998 Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: [1745967486331] ControllerWebradio::clearAddPlayTrack Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand stop took 40 milliseconds Apr 30 06:58:06 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand stop took 9 milliseconds Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:06 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:06 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:06 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand status took 16 milliseconds Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand clear took 16 milliseconds Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand status took 15 milliseconds Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1742998" Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:06 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:06 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:06 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:06 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 52ms Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 51ms Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:06 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:06 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:06 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 48ms Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:06 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 68ms Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1742998" took 259 milliseconds Apr 30 06:58:06 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:58:06 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:06 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 227ms Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 226ms Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:06 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:06 volumio volumio[1780]: info: Apr 30 06:58:06 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:06 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:06 volumio volumio[1780]: info: sendMpdCommand play took 11 milliseconds Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 9ms Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 6ms Apr 30 06:58:06 volumio volumio[1780]: info: ------------------------------ 5ms Apr 30 06:58:07 volumio volumio[1780]: info: Apr 30 06:58:07 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:07 volumio volumio[1780]: info: Apr 30 06:58:07 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:07 volumio volumio[1780]: info: Apr 30 06:58:07 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand status took 46 milliseconds Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:07 volumio volumio[1780]: info: Apr 30 06:58:07 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:07 volumio volumio[1780]: info: Apr 30 06:58:07 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand status took 52 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand status took 40 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: 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":"1003","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:07 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:07 volumio volumio[1780]: info: ------------------------------ 68ms Apr 30 06:58:07 volumio volumio[1780]: info: Apr 30 06:58:07 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand status took 17 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: 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":"1003","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:07 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:07 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: 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":"1003","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:07 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:07 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: 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":"1003","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:07 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:07 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: ------------------------------ 175ms Apr 30 06:58:07 volumio volumio[1780]: info: ------------------------------ 163ms Apr 30 06:58:07 volumio volumio[1780]: info: ------------------------------ 130ms Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand status took 114 milliseconds Apr 30 06:58:07 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 101 milliseconds Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:07 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:07 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: 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":"1003","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:07 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:07 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:07 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:07 volumio volumio[1780]: info: ------------------------------ 169ms Apr 30 06:58:08 volumio volumio[1780]: info: Apr 30 06:58:08 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:08 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:08 volumio volumio[1780]: info: Apr 30 06:58:08 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:08 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:08 volumio volumio[1780]: 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":"1003","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:08 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:08 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 215ms Apr 30 06:58:08 volumio volumio[1780]: info: Apr 30 06:58:08 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:08 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:08 volumio volumio[1780]: info: Apr 30 06:58:08 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:08 volumio volumio[1780]: info: Apr 30 06:58:08 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:08 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:08 volumio volumio[1780]: info: Apr 30 06:58:08 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 53ms Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand status took 51 milliseconds Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 11ms Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand status took 9 milliseconds Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 30 06:58:08 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:08 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:08 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:08 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Clash - Should I Stay or Should I Go - Remastered","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:08 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:08 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:08 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:08 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Clash - Should I Stay or Should I Go - Remastered","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:08 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:08 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:08 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:08 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Clash - Should I Stay or Should I Go - Remastered","artist":"113.fm Awesome 80's","album":null,"uri":"http://75.102.53.58:80/1003","trackType":"58:80/1003"} Apr 30 06:58:08 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:08 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:08 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 167ms Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 125ms Apr 30 06:58:08 volumio volumio[1780]: info: ------------------------------ 123ms Apr 30 06:58:15 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:58:15 volumio volumio[1780]: info: [1745967495709] ControllerWebradio::stop Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:58:15 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:15 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1863812 Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: [1745967495740] ControllerWebradio::clearAddPlayTrack Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:15 volumio volumio[1780]: info: Apr 30 06:58:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand stop took 39 milliseconds Apr 30 06:58:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand stop took 9 milliseconds Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:58:15 volumio volumio[1780]: info: Apr 30 06:58:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:15 volumio volumio[1780]: info: Apr 30 06:58:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:15 volumio volumio[1780]: info: Apr 30 06:58:15 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:15 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand status took 10 milliseconds Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand clear took 8 milliseconds Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand status took 7 milliseconds Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1863812" Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:15 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:15 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:15 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:15 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:15 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:15 volumio volumio[1780]: info: ------------------------------ 50ms Apr 30 06:58:15 volumio volumio[1780]: info: Apr 30 06:58:15 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:15 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:15 volumio volumio[1780]: info: Apr 30 06:58:15 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:15 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:15 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:58:15 volumio volumio[1780]: info: ------------------------------ 60ms Apr 30 06:58:15 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 55 milliseconds Apr 30 06:58:15 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:15 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:58:15 volumio volumio[1780]: info: ------------------------------ 72ms Apr 30 06:58:16 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1863812" took 260 milliseconds Apr 30 06:58:16 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:58:16 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:58:16 volumio volumio[1780]: info: Apr 30 06:58:16 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:16 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:16 volumio volumio[1780]: info: ------------------------------ 222ms Apr 30 06:58:16 volumio volumio[1780]: info: ------------------------------ 220ms Apr 30 06:58:16 volumio volumio[1780]: info: sendMpdCommand play took 4 milliseconds Apr 30 06:58:16 volumio volumio[1780]: info: Apr 30 06:58:16 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:16 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:16 volumio volumio[1780]: info: Apr 30 06:58:16 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:16 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:16 volumio volumio[1780]: info: ------------------------------ 9ms Apr 30 06:58:16 volumio volumio[1780]: info: ------------------------------ 6ms Apr 30 06:58:16 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 44 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 42 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 40 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: 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":"classical_mobile_aac","artist":"1.FM - Otto's Classical Music (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: 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":"classical_mobile_aac","artist":"1.FM - Otto's Classical Music (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: 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":"classical_mobile_aac","artist":"1.FM - Otto's Classical Music (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 87ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 125ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 124ms Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 80 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 9 milliseconds Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: 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":"classical_mobile_aac","artist":"1.FM - Otto's Classical Music (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 135ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 46 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: 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":"classical_mobile_aac","artist":"1.FM - Otto's Classical Music (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: 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":"classical_mobile_aac","artist":"1.FM - Otto's Classical Music (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 126ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 125ms Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 23ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 21 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 21ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 18 milliseconds Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:18 volumio volumio[1780]: info: Apr 30 06:58:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 40ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 30 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 28ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 26 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 14ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 10ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand status took 9 milliseconds Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"AUFSCHNAITER - L'Orfeo Barockorchester, Gaigg - Serenade No.4 à 5 in A minor","artist":"classical","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":587,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"AUFSCHNAITER - L'Orfeo Barockorchester, Gaigg - Serenade No.4 à 5 in A minor","artist":"classical","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 115ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 112ms Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 71 milliseconds Apr 30 06:58:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 70 milliseconds Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"AUFSCHNAITER - L'Orfeo Barockorchester, Gaigg - Serenade No.4 à 5 in A minor","artist":"classical","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"AUFSCHNAITER - L'Orfeo Barockorchester, Gaigg - Serenade No.4 à 5 in A minor","artist":"classical","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"AUFSCHNAITER - L'Orfeo Barockorchester, Gaigg - Serenade No.4 à 5 in A minor","artist":"classical","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:18 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"AUFSCHNAITER - L'Orfeo Barockorchester, Gaigg - Serenade No.4 à 5 in A minor","artist":"classical","album":null,"uri":"http://185.33.21.112:80/classical_mobile_aac","trackType":""} Apr 30 06:58:18 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:18 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 229ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 217ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 201ms Apr 30 06:58:18 volumio volumio[1780]: info: ------------------------------ 199ms Apr 30 06:58:25 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:58:25 volumio volumio[1780]: info: [1745967505928] ControllerWebradio::stop Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:58:25 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:25 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99541143 Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: [1745967505959] ControllerWebradio::clearAddPlayTrack Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:25 volumio volumio[1780]: info: Apr 30 06:58:25 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:25 volumio volumio[1780]: info: sendMpdCommand stop took 37 milliseconds Apr 30 06:58:25 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:25 volumio volumio[1780]: info: sendMpdCommand stop took 8 milliseconds Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:58:25 volumio volumio[1780]: info: Apr 30 06:58:25 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:25 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:25 volumio volumio[1780]: info: Apr 30 06:58:25 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:25 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:25 volumio volumio[1780]: info: Apr 30 06:58:25 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:25 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:25 volumio volumio[1780]: info: sendMpdCommand status took 9 milliseconds Apr 30 06:58:25 volumio volumio[1780]: info: sendMpdCommand clear took 7 milliseconds Apr 30 06:58:25 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:25 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99541143" Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:25 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:25 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:25 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:25 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:25 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:25 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 47ms Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 46ms Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:26 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:26 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:26 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 50ms Apr 30 06:58:26 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 30 06:58:26 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:26 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 61ms Apr 30 06:58:26 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99541143" took 256 milliseconds Apr 30 06:58:26 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:58:26 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:26 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 220ms Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 219ms Apr 30 06:58:26 volumio volumio[1780]: info: sendMpdCommand play took 5 milliseconds Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:26 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:26 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 9ms Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 6ms Apr 30 06:58:26 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:26 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:26 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:26 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:26 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:26 volumio volumio[1780]: info: Apr 30 06:58:26 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:26 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:26 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 46 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 48 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 47 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: 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":"stream","artist":"(#1 - 182/900000) Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 60ms Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: 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":"stream","artist":"(#1 - 182/900000) Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: 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":"stream","artist":"(#1 - 182/900000) Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: 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":"stream","artist":"(#1 - 182/900000) Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 180ms Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 185ms Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 144ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 124 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: 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":"stream","artist":"(#1 - 182/900000) Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: 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":"stream","artist":"(#1 - 182/900000) Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 197ms Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 197ms Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:27 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:27 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:27 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 10ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 7 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:27 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 15ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 13 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 11ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 8 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radio kantipur - radio kantipur","artist":"Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 54ms Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:27 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 48ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 47 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 7ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":586,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radio kantipur - radio kantipur","artist":"Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radio kantipur - radio kantipur","artist":"Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 126ms Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 123ms Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:27 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:27 volumio volumio[1780]: info: Apr 30 06:58:27 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 83 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 83 milliseconds Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 16ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand status took 15 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radio kantipur - radio kantipur","artist":"Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radio kantipur - radio kantipur","artist":"Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 198ms Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 157ms Apr 30 06:58:27 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 71 milliseconds Apr 30 06:58:27 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:27 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:27 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:27 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radio kantipur - radio kantipur","artist":"Radio Kantipur FM 96.1 MHz","album":null,"uri":"http://103.48.88.67:7248/stream","trackType":""} Apr 30 06:58:27 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:27 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:27 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:27 volumio volumio[1780]: info: ------------------------------ 119ms Apr 30 06:58:34 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:58:34 volumio volumio[1780]: info: [1745967514205] ControllerWebradio::stop Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:58:34 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:34 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=754525 Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: [1745967514235] ControllerWebradio::clearAddPlayTrack Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand stop took 36 milliseconds Apr 30 06:58:34 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand stop took 7 milliseconds Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:34 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:34 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:34 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand status took 8 milliseconds Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand clear took 8 milliseconds Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=754525" Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:34 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:34 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:34 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:34 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 46ms Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 45ms Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:34 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:34 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:34 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 57ms Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:34 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 68ms Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=754525" took 260 milliseconds Apr 30 06:58:34 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:58:34 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:34 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 225ms Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 216ms Apr 30 06:58:34 volumio volumio[1780]: info: sendMpdCommand play took 4 milliseconds Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:34 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:34 volumio volumio[1780]: info: Apr 30 06:58:34 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:34 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 5ms Apr 30 06:58:34 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:39 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand status took 62 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand status took 60 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand status took 59 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:39 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:39 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:39 volumio volumio[1780]: info: Apr 30 06:58:39 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 16ms Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 12ms Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand status took 11 milliseconds Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:39 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:39 volumio volumio[1780]: 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":"128 Kbps","isStreaming":false,"title":"M.G.Srikumar,chithra - Enswasakaatre","artist":"A9RADIO","album":null,"uri":"http://listen.radionomy.com:80/A9RADIO","trackType":""} Apr 30 06:58:39 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:39 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:39 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"M.G.Srikumar,chithra - Enswasakaatre","artist":"A9RADIO","album":null,"uri":"http://listen.radionomy.com:80/A9RADIO","trackType":""} Apr 30 06:58:39 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:39 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:39 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:39 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"M.G.Srikumar,chithra - Enswasakaatre","artist":"A9RADIO","album":null,"uri":"http://listen.radionomy.com:80/A9RADIO","trackType":""} Apr 30 06:58:39 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:39 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 115ms Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 148ms Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 147ms Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 89ms Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand status took 88 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 78 milliseconds Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:39 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:39 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"M.G.Srikumar,chithra - Enswasakaatre","artist":"A9RADIO","album":null,"uri":"http://listen.radionomy.com:80/A9RADIO","trackType":""} Apr 30 06:58:39 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:39 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:39 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:39 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"M.G.Srikumar,chithra - Enswasakaatre","artist":"A9RADIO","album":null,"uri":"http://listen.radionomy.com:80/A9RADIO","trackType":""} Apr 30 06:58:39 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:39 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 181ms Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 175ms Apr 30 06:58:39 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 97 milliseconds Apr 30 06:58:39 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:39 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:39 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:39 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"M.G.Srikumar,chithra - Enswasakaatre","artist":"A9RADIO","album":null,"uri":"http://listen.radionomy.com:80/A9RADIO","trackType":""} Apr 30 06:58:39 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:39 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:39 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:39 volumio volumio[1780]: info: ------------------------------ 237ms Apr 30 06:58:51 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:58:51 volumio volumio[1780]: info: [1745967531716] ControllerWebradio::stop Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:58:51 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:51 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540882 Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: [1745967531753] ControllerWebradio::clearAddPlayTrack Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:51 volumio volumio[1780]: info: Apr 30 06:58:51 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand stop took 42 milliseconds Apr 30 06:58:51 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand stop took 7 milliseconds Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:58:51 volumio volumio[1780]: info: Apr 30 06:58:51 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:51 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:51 volumio volumio[1780]: info: Apr 30 06:58:51 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:51 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:51 volumio volumio[1780]: info: Apr 30 06:58:51 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:51 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand status took 7 milliseconds Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand clear took 7 milliseconds Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540882" Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:51 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:51 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:51 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:51 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:51 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:58:51 volumio volumio[1780]: info: ------------------------------ 41ms Apr 30 06:58:51 volumio volumio[1780]: info: ------------------------------ 40ms Apr 30 06:58:51 volumio volumio[1780]: info: Apr 30 06:58:51 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:51 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:51 volumio volumio[1780]: info: Apr 30 06:58:51 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:51 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:51 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:58:51 volumio volumio[1780]: info: ------------------------------ 44ms Apr 30 06:58:51 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 30 06:58:51 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:51 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:58:51 volumio volumio[1780]: info: ------------------------------ 55ms Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540882" took 256 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:58:52 volumio volumio[1780]: info: Apr 30 06:58:52 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:52 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 225ms Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 223ms Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand play took 4 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: Apr 30 06:58:52 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:52 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:52 volumio volumio[1780]: info: Apr 30 06:58:52 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:52 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:58:52 volumio volumio[1780]: info: Apr 30 06:58:52 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:52 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:52 volumio volumio[1780]: info: Apr 30 06:58:52 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:52 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:52 volumio volumio[1780]: info: Apr 30 06:58:52 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:52 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand status took 21 milliseconds Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand status took 14 milliseconds Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand status took 30 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand clearerror took 20 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand clearerror took 18 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:52 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:52 volumio volumio[1780]: 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 - 34/999) Alseraj Radio","album":null,"uri":"http://51.91.82.4:5672/stream","trackType":""} Apr 30 06:58:52 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::syncState stateService stop Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: No code Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:52 volumio volumio[1780]: 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 - 34/999) Alseraj Radio","album":null,"uri":"http://51.91.82.4:5672/stream","trackType":""} Apr 30 06:58:52 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::syncState stateService stop Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: No code Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 156ms Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 156ms Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand clearerror took 131 milliseconds Apr 30 06:58:52 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 130 milliseconds Apr 30 06:58:52 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:52 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:52 volumio volumio[1780]: 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 - 34/999) Alseraj Radio","album":null,"uri":"http://51.91.82.4:5672/stream","trackType":""} Apr 30 06:58:52 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::syncState stateService stop Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: No code Apr 30 06:58:52 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:52 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:52 volumio volumio[1780]: info: ------------------------------ 210ms Apr 30 06:58:54 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:54 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:54 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:58:54 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:58:54 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1887632 Apr 30 06:58:54 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:58:54 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:58:54 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:54 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:58:54 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:54 volumio volumio[1780]: info: [1745967534956] ControllerWebradio::clearAddPlayTrack Apr 30 06:58:54 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:58:54 volumio volumio[1780]: info: sendMpdCommand stop took 4 milliseconds Apr 30 06:58:54 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:58:54 volumio volumio[1780]: info: Apr 30 06:58:54 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:54 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:54 volumio volumio[1780]: info: sendMpdCommand clear took 2 milliseconds Apr 30 06:58:54 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1887632" Apr 30 06:58:54 volumio volumio[1780]: info: Apr 30 06:58:54 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:54 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:54 volumio volumio[1780]: info: Apr 30 06:58:54 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:54 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:55 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:55 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 258ms Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1887632" took 258 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 260ms Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 259ms Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 5ms Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:55 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:55 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand play took 8 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 3ms Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand status took 45 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand status took 46 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand status took 45 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand status took 9 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:55 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:55 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 15/1500) Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:55 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:58:55 volumio volumio[1780]: info: ------------------------------ 64ms Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:55 volumio volumio[1780]: info: Apr 30 06:58:55 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:58:55 volumio volumio[1780]: info: sendMpdCommand status took 3 milliseconds Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:55 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:55 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:55 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 15/1500) Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:55 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:55 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:55 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:55 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 15/1500) Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:55 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:55 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:55 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:55 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:55 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 15/1500) Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:55 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:55 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:55 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 165ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 163ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 123ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 99 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 98 milliseconds Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 15/1500) Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 15/1500) Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 164ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 164ms Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:56 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:56 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:56 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:56 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 11ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand status took 11 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 9ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand status took 8 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:56 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:58:56 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:58:56 volumio volumio[1780]: info: Apr 30 06:58:56 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 15ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand status took 13 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 10ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand status took 8 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":766,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 128ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 127ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 125ms Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 105 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 104 milliseconds Apr 30 06:58:56 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 104 milliseconds Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:56 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:58:56 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:58:56 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"Szepviz Radio","album":null,"uri":"http://86.123.109.20:8000/","trackType":"20:8000/"} Apr 30 06:58:56 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:58:56 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:58:56 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 218ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 212ms Apr 30 06:58:56 volumio volumio[1780]: info: ------------------------------ 210ms Apr 30 06:59:03 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:59:03 volumio volumio[1780]: info: [1745967543131] ControllerWebradio::stop Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:59:03 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:59:03 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1868960 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: [1745967543160] ControllerWebradio::clearAddPlayTrack Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand stop took 35 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand stop took 7 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 7 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand clear took 7 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1868960" Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 31ms Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 32 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 32ms Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:59:03 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 53ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 58ms Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1868960" took 259 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 238ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 213ms Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand play took 3 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 7ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 5ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 3ms Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 35 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 38 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 36 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"103.253.132.4:5000","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:03 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 47ms Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"103.253.132.4:5000","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:03 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:03 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"103.253.132.4:5000","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:03 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:03 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 123ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 122ms Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 75 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 74 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"103.253.132.4:5000","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:03 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:03 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"103.253.132.4:5000","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:03 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:03 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"103.253.132.4:5000","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:03 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:03 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:03 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 185ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 179ms Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 178ms Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:03 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:03 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 129ms Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 128 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 10ms Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 8 milliseconds Apr 30 06:59:03 volumio volumio[1780]: info: ------------------------------ 7ms Apr 30 06:59:03 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:03 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:03 volumio volumio[1780]: info: Apr 30 06:59:03 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:04 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:04 volumio volumio[1780]: info: Apr 30 06:59:04 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:04 volumio volumio[1780]: info: Apr 30 06:59:04 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:04 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:04 volumio volumio[1780]: info: Apr 30 06:59:04 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 23ms Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand status took 21 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 16ms Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand status took 16 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 15ms Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand status took 13 milliseconds Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:04 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:04 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Music Freeze Your Mind","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:04 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:04 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:04 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:04 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Music Freeze Your Mind","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:04 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:04 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:04 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:04 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Music Freeze Your Mind","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:04 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:04 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 262ms Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 143ms Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 141ms Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 117 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 116 milliseconds Apr 30 06:59:04 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 116 milliseconds Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:04 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:04 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:04 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Music Freeze Your Mind","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:04 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:04 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:04 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:04 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Music Freeze Your Mind","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:04 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:04 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:04 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:04 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Music Freeze Your Mind","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.132.4:5000","trackType":"4:5000"} Apr 30 06:59:04 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:04 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:04 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 272ms Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 265ms Apr 30 06:59:04 volumio volumio[1780]: info: ------------------------------ 264ms Apr 30 06:59:14 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:59:14 volumio volumio[1780]: info: [1745967554472] ControllerWebradio::stop Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:59:14 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:59:14 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791 Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: [1745967554501] ControllerWebradio::clearAddPlayTrack Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand stop took 35 milliseconds Apr 30 06:59:14 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand stop took 7 milliseconds Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:14 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:14 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:14 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand clear took 11 milliseconds Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand status took 10 milliseconds Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791" Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:14 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:59:14 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:14 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:14 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 58ms Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 57ms Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:14 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:14 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:14 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 57ms Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:14 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 71ms Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791" took 261 milliseconds Apr 30 06:59:14 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:59:14 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:14 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 218ms Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 216ms Apr 30 06:59:14 volumio volumio[1780]: info: sendMpdCommand play took 4 milliseconds Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:14 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:14 volumio volumio[1780]: info: Apr 30 06:59:14 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:14 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 6ms Apr 30 06:59:14 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:15 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:15 volumio volumio[1780]: info: sendMpdCommand status took 8 milliseconds Apr 30 06:59:15 volumio volumio[1780]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:15 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:15 volumio volumio[1780]: info: Apr 30 06:59:15 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:15 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:15 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:16 volumio volumio[1780]: info: Apr 30 06:59:16 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:16 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:16 volumio volumio[1780]: info: Apr 30 06:59:16 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand status took 24 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 24ms Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand status took 22 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 19ms Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand status took 17 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 10ms Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:16 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:16 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":583,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Israel & Rodolffo - Moral Da História","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Apr 30 06:59:16 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:16 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:16 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":685,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Israel & Rodolffo - Moral Da História","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Apr 30 06:59:16 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:16 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 57ms Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 78ms Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand status took 57 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 48 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 49 milliseconds Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:16 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:16 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Israel & Rodolffo - Moral Da História","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Apr 30 06:59:16 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:16 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:16 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:16 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Israel & Rodolffo - Moral Da História","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Apr 30 06:59:16 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:16 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:16 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:16 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Israel & Rodolffo - Moral Da História","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Apr 30 06:59:16 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:16 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 217ms Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 216ms Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 211ms Apr 30 06:59:16 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 158 milliseconds Apr 30 06:59:16 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:16 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:16 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:16 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Israel & Rodolffo - Moral Da História","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Apr 30 06:59:16 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:16 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:16 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:16 volumio volumio[1780]: info: ------------------------------ 259ms Apr 30 06:59:18 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::ClearQueue Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::serviceStop Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::serviceStop Apr 30 06:59:18 volumio volumio[1780]: info: [1745967558622] ControllerWebradio::stop Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::clearPlayQueue Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::addQueueItems Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::addQueueItems Apr 30 06:59:18 volumio volumio[1780]: info: Preload queue cleared Apr 30 06:59:18 volumio volumio[1780]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99578163 Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::saveQueue Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::updateTrackBlock Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrackBlock Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPlay Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::play index 0 Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::stop Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::play index undefined Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: [1745967558655] ControllerWebradio::clearAddPlayTrack Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand stop took 39 milliseconds Apr 30 06:59:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand stop took 7 milliseconds Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:18 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand status took 13 milliseconds Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand clear took 13 milliseconds Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand status took 11 milliseconds Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand status took 9 milliseconds Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99578163" Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:59:18 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:18 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:18 volumio volumio[1780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 56ms Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 55ms Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:18 volumio volumio[1780]: error: updateQueue error: null Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 53ms Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:18 volumio volumio[1780]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 69ms Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99578163" took 256 milliseconds Apr 30 06:59:18 volumio volumio[1780]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:59:18 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 215ms Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 214ms Apr 30 06:59:18 volumio volumio[1780]: info: sendMpdCommand play took 4 milliseconds Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:18 volumio volumio[1780]: info: Apr 30 06:59:18 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:18 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 8ms Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 4ms Apr 30 06:59:18 volumio volumio[1780]: info: ------------------------------ 3ms Apr 30 06:59:19 volumio volumio[1780]: info: Apr 30 06:59:19 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:19 volumio volumio[1780]: info: Apr 30 06:59:19 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:19 volumio volumio[1780]: info: Apr 30 06:59:19 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand status took 44 milliseconds Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:19 volumio volumio[1780]: info: Apr 30 06:59:19 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand status took 39 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand status took 39 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:19 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:19 volumio volumio[1780]: 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":"","artist":"(#1 - 116/2000) Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:19 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:59:19 volumio volumio[1780]: info: ------------------------------ 55ms Apr 30 06:59:19 volumio volumio[1780]: info: Apr 30 06:59:19 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:19 volumio volumio[1780]: info: Apr 30 06:59:19 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand status took 11 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:19 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:19 volumio volumio[1780]: 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":"","artist":"(#1 - 116/2000) Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:19 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:19 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:19 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:19 volumio volumio[1780]: 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":"","artist":"(#1 - 116/2000) Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:19 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:19 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: ------------------------------ 120ms Apr 30 06:59:19 volumio volumio[1780]: info: ------------------------------ 119ms Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 69 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 68 milliseconds Apr 30 06:59:19 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 67 milliseconds Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:19 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:19 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:19 volumio volumio[1780]: 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":"","artist":"(#1 - 116/2000) Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:19 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:19 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:19 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:19 volumio volumio[1780]: 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":"","artist":"(#1 - 116/2000) Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:19 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:19 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:19 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:19 volumio volumio[1780]: 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":"","artist":"(#1 - 116/2000) Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:19 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:19 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:19 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:19 volumio volumio[1780]: info: ------------------------------ 173ms Apr 30 06:59:19 volumio volumio[1780]: info: ------------------------------ 166ms Apr 30 06:59:19 volumio volumio[1780]: info: ------------------------------ 166ms Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:20 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:20 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:20 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:20 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 20ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand status took 18 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 18ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand status took 17 milliseconds Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:20 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 24ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand status took 22 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 21ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand status took 10 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:20 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:20 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Presents; NETWORK SATELLITE - NETWORK SATELLITE - WEEKLY PODCAST - #03-2015","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:20 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:20 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:20 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:20 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Presents; NETWORK SATELLITE - NETWORK SATELLITE - WEEKLY PODCAST - #03-2015","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:20 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:20 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 103ms Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 101ms Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces system playlist update Apr 30 06:59:20 volumio volumio[1780]: info: Ignoring MPD Status Update Apr 30 06:59:20 volumio volumio[1780]: info: Apr 30 06:59:20 volumio volumio[1780]: ---------------------------- MPD announces state update: player Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::getState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 85ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand status took 85 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 6ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseState Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:20 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:20 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":680,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Presents; NETWORK SATELLITE - NETWORK SATELLITE - WEEKLY PODCAST - #03-2015","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:20 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:20 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:20 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:20 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Presents; NETWORK SATELLITE - NETWORK SATELLITE - WEEKLY PODCAST - #03-2015","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:20 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:20 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 168ms Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 157ms Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 80 milliseconds Apr 30 06:59:20 volumio volumio[1780]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:20 volumio volumio[1780]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:20 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:20 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Presents; NETWORK SATELLITE - NETWORK SATELLITE - WEEKLY PODCAST - #03-2015","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:20 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:20 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: ControllerMpd::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::servicePushState Apr 30 06:59:20 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 30 06:59:20 volumio volumio[1780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Presents; NETWORK SATELLITE - NETWORK SATELLITE - WEEKLY PODCAST - #03-2015","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Apr 30 06:59:20 volumio volumio[1780]: verbose: CURRENT POSITION 0 Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState stateService play Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:59:20 volumio volumio[1780]: info: Received an update from plugin. extracting info from payload Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 30 06:59:20 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 227ms Apr 30 06:59:20 volumio volumio[1780]: info: ------------------------------ 147ms Apr 30 06:59:33 volumio volumio[1780]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 06:59:33 volumio volumio[1780]: Error: connect ETIMEDOUT 108.160.169.174:443 Apr 30 06:59:33 volumio volumio[1780]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 30 06:59:33 volumio volumio[1780]: errno: -110, Apr 30 06:59:33 volumio volumio[1780]: code: 'ETIMEDOUT', Apr 30 06:59:33 volumio volumio[1780]: syscall: 'connect', Apr 30 06:59:33 volumio volumio[1780]: address: '108.160.169.174', Apr 30 06:59:33 volumio volumio[1780]: port: 443 Apr 30 06:59:33 volumio volumio[1780]: } Apr 30 06:59:33 volumio volumio[1780]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 06:59:34 volumio sudo[5239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 06:58 Apr 30 06:59:34 volumio sudo[5239]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"