-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2024-09-01 17:46:57 CST. -- Sep 01 17:45:02 volumio volumio[2804]: info: VolumeController::SetAlsaVolume77 Sep 01 17:45:02 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:02 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:02 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:03 volumio volumio[2804]: info: VolumeController::SetAlsaVolume49 Sep 01 17:45:03 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:03 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:03 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 17:45:13 volumio volumio[2804]: info: Getting Alsa Cards List without I2S DAC Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 01 17:45:13 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 17:45:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 17:45:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 17:45:17 volumio volumio[2804]: info: Discovery: Getting this device information Sep 01 17:45:17 volumio volumio[2804]: info: CoreCommandRouter::volumioGetState Sep 01 17:45:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:28 volumio volumio[2804]: info: Retrieving Cloud Streaming UI Sep 01 17:45:28 volumio volumio[2804]: info: Getting Tidal Cloud Configuration Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:28 volumio volumio[2804]: info: Getting Qobuz Cloud Configuration Sep 01 17:45:28 volumio volumio[2804]: info: Asking plugin for UI Config Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:28 volumio volumio[2804]: info: Getting Spotify Cloud Configuration Sep 01 17:45:28 volumio volumio[2804]: info: Asking plugin for UI Config Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:28 volumio volumio[2804]: info: Saving Spotify Acccount Sep 01 17:45:28 volumio volumio[2804]: info: Got Tidal Cloud Configuration Sep 01 17:45:28 volumio volumio[2804]: info: Got it Sep 01 17:45:28 volumio volumio[2804]: info: Got it Sep 01 17:45:28 volumio volumio[2804]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:45:28 volumio volumio[2804]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:45:28 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 17:45:32 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:45:32 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 01 17:45:32 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 01 17:45:36 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand rescan took 3 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 129 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 128 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 74 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 12 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 10 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 10 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 10 milliseconds Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:37 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:37 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:37 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:37 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:37 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:37 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:37 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:37 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:37 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:37 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:37 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:37 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: ------------------------------ 249ms Sep 01 17:45:37 volumio volumio[2804]: info: ------------------------------ 246ms Sep 01 17:45:37 volumio volumio[2804]: info: ------------------------------ 246ms Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 101 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 79 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 58 milliseconds Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:37 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:37 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:37 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:37 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:37 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:37 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:37 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:37 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:37 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:37 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:37 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:37 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:37 volumio volumio[2804]: info: ------------------------------ 293ms Sep 01 17:45:37 volumio volumio[2804]: info: ------------------------------ 175ms Sep 01 17:45:37 volumio volumio[2804]: info: ------------------------------ 175ms Sep 01 17:45:37 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: Sep 01 17:45:37 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:37 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:37 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:37 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:38 volumio volumio[2804]: info: Sep 01 17:45:38 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:38 volumio volumio[2804]: info: Sep 01 17:45:38 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand status took 98 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand status took 98 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 58 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:38 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:38 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:38 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:38 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:38 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:38 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:38 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:38 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:38 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:38 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:38 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:38 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: ------------------------------ 226ms Sep 01 17:45:38 volumio volumio[2804]: info: ------------------------------ 225ms Sep 01 17:45:38 volumio volumio[2804]: info: ------------------------------ 224ms Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 105 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 82 milliseconds Sep 01 17:45:38 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 61 milliseconds Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:38 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:38 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:38 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:38 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:38 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:38 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:38 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:38 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:38 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:38 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:38 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:38 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:38 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:38 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:38 volumio volumio[2804]: info: ------------------------------ 262ms Sep 01 17:45:38 volumio volumio[2804]: info: ------------------------------ 168ms Sep 01 17:45:38 volumio volumio[2804]: info: ------------------------------ 167ms Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand update Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: Sep 01 17:45:40 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand update took 2 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: Sep 01 17:45:40 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: Sep 01 17:45:40 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: Sep 01 17:45:40 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: Sep 01 17:45:40 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: Sep 01 17:45:40 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 104 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 104 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 68 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:40 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:40 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:40 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:40 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:40 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:40 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:40 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:40 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:40 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:40 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:40 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:40 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: ------------------------------ 225ms Sep 01 17:45:40 volumio volumio[2804]: info: ------------------------------ 225ms Sep 01 17:45:40 volumio volumio[2804]: info: ------------------------------ 223ms Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 98 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 76 milliseconds Sep 01 17:45:40 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 54 milliseconds Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:40 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:40 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:40 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:40 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:40 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:40 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:40 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47177,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:40 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:40 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:40 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:40 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47177,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:40 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:40 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:40 volumio volumio[2804]: info: ------------------------------ 264ms Sep 01 17:45:40 volumio volumio[2804]: info: ------------------------------ 164ms Sep 01 17:45:40 volumio volumio[2804]: info: ------------------------------ 164ms Sep 01 17:45:40 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand update Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand update took 2 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 93 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 93 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 57 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:43 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:43 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:43 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:43 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:43 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:43 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:43 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:43 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:43 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:43 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:43 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:43 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: ------------------------------ 236ms Sep 01 17:45:43 volumio volumio[2804]: info: ------------------------------ 236ms Sep 01 17:45:43 volumio volumio[2804]: info: ------------------------------ 235ms Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 122 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 95 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 74 milliseconds Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:43 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:43 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:43 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:43 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:43 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:43 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:43 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:43 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:43 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:43 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:43 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:43 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:43 volumio volumio[2804]: info: ------------------------------ 285ms Sep 01 17:45:43 volumio volumio[2804]: info: ------------------------------ 196ms Sep 01 17:45:43 volumio volumio[2804]: info: ------------------------------ 195ms Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: Sep 01 17:45:43 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:43 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:45:43 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:43 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:43 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 111 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 111 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 72 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 49 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 239ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 238ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 236ms Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 105 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 90 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 288ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 183ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 181ms Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: Sep 01 17:45:44 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 100 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 99 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 59 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 212ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 211ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 211ms Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 91 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 01 17:45:44 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 47 milliseconds Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:45:44 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:45:44 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:45:44 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:45:44 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 249ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 154ms Sep 01 17:45:44 volumio volumio[2804]: info: ------------------------------ 153ms Sep 01 17:45:44 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:45:47 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 17:45:47 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 17:45:47 volumio volumio[2804]: info: Discovery: Getting this device information Sep 01 17:45:47 volumio volumio[2804]: info: CoreCommandRouter::volumioGetState Sep 01 17:45:47 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 17:45:54 volumio kernel: usb 2-2.4: USB disconnect, device number 3 Sep 01 17:45:57 volumio volumio[2804]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2.4/2-2.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/MusicBox /dev/disk/by-partuuid/f0854389-8208-4f3d-a61d-9753b13a8cae /dev/disk/by-id/usb-External_USB3.0_201703310007F-0:0-part1 /dev/disk/by-uuid/90CCB0C0CCB0A1C0 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2.4:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2.4/2-2.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"MusicBox","ID_FS_LABEL_ENC":"MusicBox","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"90CCB0C0CCB0A1C0","ID_FS_UUID_ENC":"90CCB0C0CCB0A1C0","ID_INSTANCE":"0:0","ID_MODEL":"USB3.0","ID_MODEL_ENC":"USB3.0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"1953521664","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"f0854389-8208-4f3d-a61d-9753b13a8cae","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"aa82afe5-006a-4fb8-9961-b4a4db05634b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_2_4_1_0-scsi-0_0_0_0","ID_REVISION":"0204","ID_SERIAL":"External_USB3.0_201703310007F-0:0","ID_SERIAL_SHORT":"201703310007F","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"External","ID_VENDOR_ENC":"External","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2439","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4193842"} Sep 01 17:45:57 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 01 17:45:58 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 01 17:46:00 volumio kernel: usb 2-2.4: new SuperSpeed USB device number 4 using xhci_hcd Sep 01 17:46:00 volumio kernel: usb 2-2.4: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.04 Sep 01 17:46:00 volumio kernel: usb 2-2.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 01 17:46:00 volumio kernel: usb 2-2.4: Product: External USB 3.0 Sep 01 17:46:00 volumio kernel: usb 2-2.4: Manufacturer: JMicron Sep 01 17:46:00 volumio kernel: usb 2-2.4: SerialNumber: 201703310007F Sep 01 17:46:00 volumio kernel: scsi host1: uas Sep 01 17:46:00 volumio kernel: scsi 1:0:0:0: Direct-Access External USB3.0 0204 PQ: 0 ANSI: 6 Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 53 00 00 08 Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Disabling FUA Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 01 17:46:00 volumio kernel: sdb: sdb1 Sep 01 17:46:00 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Sep 01 17:46:00 volumio volumio[2804]: info: Mounting Device MusicBox Sep 01 17:46:00 volumio volumio[2804]: /bin/mkdir: cannot create directory ‘/mnt/USB/MusicBox’: File exists Sep 01 17:46:00 volumio volumio[2804]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/MusicBox" Sep 01 17:46:00 volumio volumio[2804]: /bin/mkdir: cannot create directory ‘/mnt/USB/MusicBox’: File exists Sep 01 17:46:00 volumio sudo[3328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/MusicBox -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 01 17:46:00 volumio sudo[3328]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:46:01 volumio sudo[3328]: pam_unix(sudo:session): session closed for user root Sep 01 17:46:01 volumio volumio[2804]: ntfs-3g-mount: failed to access mountpoint /media/MusicBox: Transport endpoint is not connected Sep 01 17:46:01 volumio volumio[2804]: error: Failed to mount MusicBox: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/MusicBox" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 01 17:46:01 volumio volumio[2804]: ntfs-3g-mount: failed to access mountpoint /media/MusicBox: Transport endpoint is not connected Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:46:14 volumio volumio[2804]: info: Retrieving Cloud Streaming UI Sep 01 17:46:14 volumio volumio[2804]: info: Getting Tidal Cloud Configuration Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:46:14 volumio volumio[2804]: info: Getting Qobuz Cloud Configuration Sep 01 17:46:14 volumio volumio[2804]: info: Asking plugin for UI Config Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:46:14 volumio volumio[2804]: info: Getting Spotify Cloud Configuration Sep 01 17:46:14 volumio volumio[2804]: info: Asking plugin for UI Config Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:46:14 volumio volumio[2804]: info: Saving Spotify Acccount Sep 01 17:46:14 volumio volumio[2804]: info: Got Tidal Cloud Configuration Sep 01 17:46:14 volumio volumio[2804]: info: Got it Sep 01 17:46:14 volumio volumio[2804]: info: Got it Sep 01 17:46:14 volumio volumio[2804]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 17:46:14 volumio volumio[2804]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:14 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: Sep 01 17:46:16 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: Sep 01 17:46:16 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: Sep 01 17:46:16 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: Sep 01 17:46:16 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: Sep 01 17:46:16 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: Sep 01 17:46:16 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 112 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 112 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 59 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:16 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:16 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:16 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:16 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:16 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:16 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:16 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:16 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:16 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:16 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:16 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:16 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: ------------------------------ 233ms Sep 01 17:46:16 volumio volumio[2804]: info: ------------------------------ 233ms Sep 01 17:46:16 volumio volumio[2804]: info: ------------------------------ 231ms Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 102 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 75 milliseconds Sep 01 17:46:16 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 53 milliseconds Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:16 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:16 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:16 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:16 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:16 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:16 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:16 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:16 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:16 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:16 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:16 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:16 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:16 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:16 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:16 volumio volumio[2804]: info: ------------------------------ 293ms Sep 01 17:46:16 volumio volumio[2804]: info: ------------------------------ 186ms Sep 01 17:46:16 volumio volumio[2804]: info: ------------------------------ 184ms Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: Sep 01 17:46:17 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand rescan took 1 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: Sep 01 17:46:17 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: Sep 01 17:46:17 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: Sep 01 17:46:17 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: Sep 01 17:46:17 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: Sep 01 17:46:17 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 99 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 99 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 60 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 38 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:17 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:17 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83679,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:17 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:17 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:17 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:17 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83679,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:17 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:17 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:17 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:17 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83679,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:17 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:17 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: ------------------------------ 214ms Sep 01 17:46:17 volumio volumio[2804]: info: ------------------------------ 212ms Sep 01 17:46:17 volumio volumio[2804]: info: ------------------------------ 212ms Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 95 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 73 milliseconds Sep 01 17:46:17 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 51 milliseconds Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:17 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:17 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:17 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83679,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:17 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:17 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:17 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:17 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:17 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:17 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:17 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:17 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:17 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:17 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:17 volumio volumio[2804]: info: ------------------------------ 260ms Sep 01 17:46:17 volumio volumio[2804]: info: ------------------------------ 165ms Sep 01 17:46:17 volumio volumio[2804]: info: ------------------------------ 164ms Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 17:46:17 volumio volumio[2804]: info: Discovery: Getting this device information Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::volumioGetState Sep 01 17:46:17 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 17:46:18 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:20 volumio volumio[2804]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2.4/2-2.4:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/MusicBox /dev/disk/by-partuuid/f0854389-8208-4f3d-a61d-9753b13a8cae /dev/disk/by-id/usb-External_USB3.0_201703310007F-0:0-part1 /dev/disk/by-uuid/90CCB0C0CCB0A1C0 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2.4:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2.4/2-2.4:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_FS_LABEL":"MusicBox","ID_FS_LABEL_ENC":"MusicBox","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"90CCB0C0CCB0A1C0","ID_FS_UUID_ENC":"90CCB0C0CCB0A1C0","ID_INSTANCE":"0:0","ID_MODEL":"USB3.0","ID_MODEL_ENC":"USB3.0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"1953521664","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"f0854389-8208-4f3d-a61d-9753b13a8cae","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"aa82afe5-006a-4fb8-9961-b4a4db05634b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_2_4_1_0-scsi-0_0_0_0","ID_REVISION":"0204","ID_SERIAL":"External_USB3.0_201703310007F-0:0","ID_SERIAL_SHORT":"201703310007F","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"External","ID_VENDOR_ENC":"External","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2473","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1313916922"} Sep 01 17:46:20 volumio kernel: usb 2-2.4: USB disconnect, device number 4 Sep 01 17:46:20 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Sep 01 17:46:21 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 01 17:46:22 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:25 volumio kernel: usb 2-2: USB disconnect, device number 2 Sep 01 17:46:25 volumio kernel: usb 1-1.2: USB disconnect, device number 3 Sep 01 17:46:26 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:28 volumio kernel: usb 2-1: new SuperSpeed USB device number 5 using xhci_hcd Sep 01 17:46:28 volumio kernel: usb 2-1: New USB device found, idVendor=045b, idProduct=0210, bcdDevice= 1.00 Sep 01 17:46:28 volumio kernel: usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 01 17:46:28 volumio kernel: hub 2-1:1.0: USB hub found Sep 01 17:46:28 volumio kernel: hub 2-1:1.0: 4 ports detected Sep 01 17:46:28 volumio kernel: usb 1-1.1: new high-speed USB device number 5 using xhci_hcd Sep 01 17:46:28 volumio kernel: usb 1-1.1: New USB device found, idVendor=045b, idProduct=0209, bcdDevice= 1.00 Sep 01 17:46:28 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 01 17:46:28 volumio kernel: hub 1-1.1:1.0: USB hub found Sep 01 17:46:28 volumio kernel: hub 1-1.1:1.0: 4 ports detected Sep 01 17:46:30 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:34 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:36 volumio kernel: usb 2-1.4: new SuperSpeed USB device number 6 using xhci_hcd Sep 01 17:46:36 volumio kernel: usb 2-1.4: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.04 Sep 01 17:46:36 volumio kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 01 17:46:36 volumio kernel: usb 2-1.4: Product: External USB 3.0 Sep 01 17:46:36 volumio kernel: usb 2-1.4: Manufacturer: JMicron Sep 01 17:46:36 volumio kernel: usb 2-1.4: SerialNumber: 201703310007F Sep 01 17:46:36 volumio kernel: scsi host1: uas Sep 01 17:46:36 volumio kernel: scsi 1:0:0:0: Direct-Access External USB3.0 0204 PQ: 0 ANSI: 6 Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 53 00 00 08 Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Disabling FUA Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 01 17:46:36 volumio kernel: sdb: sdb1 Sep 01 17:46:36 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Sep 01 17:46:37 volumio volumio[2804]: info: Mounting Device MusicBox Sep 01 17:46:37 volumio volumio[2804]: /bin/mkdir: cannot create directory ‘/mnt/USB/MusicBox’: File exists Sep 01 17:46:37 volumio volumio[2804]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/MusicBox" Sep 01 17:46:37 volumio volumio[2804]: /bin/mkdir: cannot create directory ‘/mnt/USB/MusicBox’: File exists Sep 01 17:46:37 volumio sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/MusicBox -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 01 17:46:37 volumio sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:46:37 volumio sudo[3414]: pam_unix(sudo:session): session closed for user root Sep 01 17:46:37 volumio volumio[2804]: ntfs-3g-mount: failed to access mountpoint /media/MusicBox: Transport endpoint is not connected Sep 01 17:46:37 volumio volumio[2804]: error: Failed to mount MusicBox: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/MusicBox" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 01 17:46:37 volumio volumio[2804]: ntfs-3g-mount: failed to access mountpoint /media/MusicBox: Transport endpoint is not connected Sep 01 17:46:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:38 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:38 volumio volumio[2804]: info: Sep 01 17:46:38 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:38 volumio volumio[2804]: info: sendMpdCommand rescan took 3 milliseconds Sep 01 17:46:38 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:38 volumio volumio[2804]: info: Sep 01 17:46:38 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:38 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:38 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:38 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:38 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:38 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: info: Sep 01 17:46:39 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: info: Sep 01 17:46:39 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 62 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 62 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:39 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:39 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:39 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:39 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: ------------------------------ 161ms Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: info: Sep 01 17:46:39 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: info: Sep 01 17:46:39 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 77 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 54 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 8 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 8 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:39 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:39 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:39 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:39 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:39 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:39 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105679,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:39 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:39 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:39 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:39 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105679,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:39 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:39 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: ------------------------------ 267ms Sep 01 17:46:39 volumio volumio[2804]: info: ------------------------------ 210ms Sep 01 17:46:39 volumio volumio[2804]: info: ------------------------------ 209ms Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 63 milliseconds Sep 01 17:46:39 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:39 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:39 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:39 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:39 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:39 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:39 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:39 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:39 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:39 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:39 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:39 volumio volumio[2804]: info: ------------------------------ 136ms Sep 01 17:46:39 volumio volumio[2804]: info: ------------------------------ 135ms Sep 01 17:46:42 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:46 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:47 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 17:46:47 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 17:46:47 volumio volumio[2804]: info: Discovery: Getting this device information Sep 01 17:46:47 volumio volumio[2804]: info: CoreCommandRouter::volumioGetState Sep 01 17:46:47 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: Sep 01 17:46:49 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: Sep 01 17:46:49 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: Sep 01 17:46:49 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: Sep 01 17:46:49 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 45 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 44 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 41 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: Sep 01 17:46:49 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: Sep 01 17:46:49 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 88 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 86 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 61 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 39 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 8 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 9 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 8 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand status took 8 milliseconds Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:49 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:49 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:49 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:49 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:49 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:49 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:49 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:49 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:49 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:49 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:49 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:49 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: ------------------------------ 236ms Sep 01 17:46:49 volumio volumio[2804]: info: ------------------------------ 234ms Sep 01 17:46:49 volumio volumio[2804]: info: ------------------------------ 194ms Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 104 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 67 milliseconds Sep 01 17:46:49 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:49 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:49 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:49 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:49 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:49 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:49 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:49 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:49 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:49 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:49 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:49 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:49 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:49 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:49 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:49 volumio volumio[2804]: info: ------------------------------ 223ms Sep 01 17:46:49 volumio volumio[2804]: info: ------------------------------ 143ms Sep 01 17:46:49 volumio volumio[2804]: info: ------------------------------ 142ms Sep 01 17:46:50 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: Sep 01 17:46:54 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand rescan took 1 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: Sep 01 17:46:54 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: Sep 01 17:46:54 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: Sep 01 17:46:54 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: Sep 01 17:46:54 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 83 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 82 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 1 milliseconds Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:54 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:54 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:54 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:54 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:54 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:54 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:54 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:54 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: ------------------------------ 182ms Sep 01 17:46:54 volumio volumio[2804]: info: ------------------------------ 181ms Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: Sep 01 17:46:54 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 80 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 59 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 9 milliseconds Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand status took 8 milliseconds Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:54 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:54 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:54 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:54 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:54 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:54 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121177,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:54 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:54 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:54 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:54 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121177,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:54 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:54 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: ------------------------------ 250ms Sep 01 17:46:54 volumio volumio[2804]: info: ------------------------------ 169ms Sep 01 17:46:54 volumio volumio[2804]: info: ------------------------------ 169ms Sep 01 17:46:54 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 01 17:46:54 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:54 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:54 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:54 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121279,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:54 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:54 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:54 volumio volumio[2804]: info: ------------------------------ 82ms Sep 01 17:46:54 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: Sep 01 17:46:55 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: Sep 01 17:46:55 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 1 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: Sep 01 17:46:55 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: Sep 01 17:46:55 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 42 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 42 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:55 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:55 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:55 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:55 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: ------------------------------ 145ms Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: Sep 01 17:46:55 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: Sep 01 17:46:55 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 79 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 53 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 29 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 7 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand status took 6 milliseconds Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:55 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:55 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:55 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:55 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:55 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:55 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:55 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:55 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:55 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:55 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:55 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:55 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: ------------------------------ 247ms Sep 01 17:46:55 volumio volumio[2804]: info: ------------------------------ 207ms Sep 01 17:46:55 volumio volumio[2804]: info: ------------------------------ 207ms Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 55 milliseconds Sep 01 17:46:55 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 33 milliseconds Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:55 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:55 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:55 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:55 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:55 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:55 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:55 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:55 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:55 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:55 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:55 volumio volumio[2804]: info: ------------------------------ 126ms Sep 01 17:46:55 volumio volumio[2804]: info: ------------------------------ 124ms Sep 01 17:46:56 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: info: Sep 01 17:46:56 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 17:46:56 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: info: Sep 01 17:46:56 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:56 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: info: Sep 01 17:46:56 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:56 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: info: Sep 01 17:46:56 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:56 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:56 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:56 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:57 volumio volumio[2804]: info: Sep 01 17:46:57 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:57 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:57 volumio volumio[2804]: info: Sep 01 17:46:57 volumio volumio[2804]: ---------------------------- MPD announces state update: update Sep 01 17:46:57 volumio volumio[2804]: info: ControllerMpd::getState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand status took 133 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand status took 132 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 82 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 60 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand status took 5 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand status took 4 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand status took 3 milliseconds Sep 01 17:46:57 volumio volumio[2804]: info: sendMpdCommand status took 2 milliseconds Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseTrackInfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: info: Command Router : Notfying DB Updatefalse Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::parseState Sep 01 17:46:57 volumio volumio[2804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 17:46:57 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:57 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:57 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:57 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:57 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:57 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:57 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:57 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:57 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:57 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:57 volumio volumio[2804]: info: ControllerMpd::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::servicePushState Sep 01 17:46:57 volumio volumio[2804]: info: CorePlayQueue::getTrack 0 Sep 01 17:46:57 volumio volumio[2804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Beatles - Revolution I","artist":"Beatles Radio","album":null,"uri":"http://64.40.104.74:8000/","trackType":"74:8000/"} Sep 01 17:46:57 volumio volumio[2804]: verbose: CURRENT POSITION 0 Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::syncState stateService play Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::syncState currentStatus play Sep 01 17:46:57 volumio volumio[2804]: info: Received an update from plugin. extracting info from payload Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreStateMachine::pushState Sep 01 17:46:57 volumio volumio[2804]: info: CoreCommandRouter::volumioPushState Sep 01 17:46:57 volumio volumio[2804]: info: ------------------------------ 255ms Sep 01 17:46:57 volumio volumio[2804]: info: ------------------------------ 255ms Sep 01 17:46:57 volumio volumio[2804]: info: ------------------------------ 253ms Sep 01 17:46:57 volumio volumio[2804]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 17:46:57 volumio volumio[2804]: Error: connect ETIMEDOUT 80.87.199.46:80 Sep 01 17:46:57 volumio volumio[2804]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 01 17:46:57 volumio volumio[2804]: errno: -110, Sep 01 17:46:57 volumio volumio[2804]: code: 'ETIMEDOUT', Sep 01 17:46:57 volumio volumio[2804]: syscall: 'connect', Sep 01 17:46:57 volumio volumio[2804]: address: '80.87.199.46', Sep 01 17:46:57 volumio volumio[2804]: port: 80 Sep 01 17:46:57 volumio volumio[2804]: } Sep 01 17:46:57 volumio volumio[2804]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 17:46:57 volumio sudo[3492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 17:45 Sep 01 17:46:57 volumio sudo[3492]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"