-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sun 2025-10-26 10:49:57 EDT. -- Oct 26 10:48:04 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 26 10:48:04 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:08 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 26 10:48:08 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:09 volumio volumio[804]: info: MyVolumio login type: Token Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:09 volumio volumio[804]: info: Retrieving Cloud Streaming UI Oct 26 10:48:09 volumio volumio[804]: info: Getting Tidal Cloud Configuration Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:09 volumio volumio[804]: info: Getting Qobuz Cloud Configuration Oct 26 10:48:09 volumio volumio[804]: info: Asking plugin for UI Config Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:09 volumio volumio[804]: info: Getting Spotify Cloud Configuration Oct 26 10:48:09 volumio volumio[804]: info: Asking plugin for UI Config Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:09 volumio volumio[804]: info: Saving Spotify Acccount Oct 26 10:48:09 volumio volumio[804]: info: Got Tidal Cloud Configuration Oct 26 10:48:09 volumio volumio[804]: info: Got it Oct 26 10:48:09 volumio volumio[804]: info: Got it Oct 26 10:48:09 volumio volumio[804]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:09 volumio volumio[804]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 10:48:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand rescan Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:13 volumio volumio[804]: info: Oct 26 10:48:13 volumio volumio[804]: ---------------------------- MPD announces state update: update Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand rescan took 4 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:13 volumio volumio[804]: info: Oct 26 10:48:13 volumio volumio[804]: ---------------------------- MPD announces state update: update Oct 26 10:48:13 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:13 volumio volumio[804]: info: Oct 26 10:48:13 volumio volumio[804]: ---------------------------- MPD announces state update: update Oct 26 10:48:13 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand status took 6 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand status took 6 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand status took 5 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand status took 5 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand status took 5 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:13 volumio volumio[804]: info: Command Router : Notfying DB Updatetrue Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::Close All Modals sent Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:13 volumio volumio[804]: info: Command Router : Notfying DB Updatetrue Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::Close All Modals sent Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:13 volumio volumio[804]: info: Command Router : Notfying DB Updatetrue Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::Close All Modals sent Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 26 10:48:13 volumio volumio[804]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:13 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:13 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:13 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:13 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":148179,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:13 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:13 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:13 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:13 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:13 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":148179,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:13 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:13 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:13 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:13 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:13 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":148179,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:13 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:13 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:13 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:13 volumio volumio[804]: info: ------------------------------ 229ms Oct 26 10:48:13 volumio volumio[804]: info: ------------------------------ 228ms Oct 26 10:48:13 volumio volumio[804]: info: ------------------------------ 228ms Oct 26 10:48:13 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 10:48:17 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 10:48:18 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 26 10:48:18 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:20 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:20 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 10:48:20 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 10:48:20 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 10:48:20 volumio volumio[804]: info: Received Get System Version Oct 26 10:48:20 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 10:48:29 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 26 10:48:29 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 26 10:48:30 volumio volumio[804]: info: Getting Alsa Cards List without I2S DAC Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 26 10:48:30 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 10:48:37 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 26 10:48:37 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:39 volumio volumio[804]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 26 10:48:39 volumio volumio[804]: info: Retrying Login Due to Network Error Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::volumioPlay Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::play index undefined Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::resume Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand play Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand play took 3 milliseconds Oct 26 10:48:40 volumio volumio[804]: info: Oct 26 10:48:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:40 volumio volumio[804]: info: Oct 26 10:48:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:40 volumio volumio[804]: info: Oct 26 10:48:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand status took 2 milliseconds Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:40 volumio volumio[804]: error: Upnp client error: Error: This socket has been ended by the other party Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 10:48:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148889,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:40 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149072,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:40 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:48:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:40 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149072,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:40 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:48:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:40 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:40 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:41 volumio volumio[804]: info: ------------------------------ 78ms Oct 26 10:48:41 volumio volumio[804]: info: ------------------------------ 77ms Oct 26 10:48:41 volumio volumio[804]: info: ------------------------------ 76ms Oct 26 10:48:41 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 26 10:48:41 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 26 10:48:41 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:41 volumio volumio[804]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 26 10:48:41 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::volumioPause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::servicePause Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::servicePause Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::pause Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand pause took 1 milliseconds Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand status took 7 milliseconds Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand status took 6 milliseconds Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand status took 3 milliseconds Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:42 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:42 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:42 volumio volumio[804]: info: ------------------------------ 49ms Oct 26 10:48:42 volumio volumio[804]: info: ------------------------------ 49ms Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:42 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:42 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:42 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:42 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:42 volumio volumio[804]: info: ------------------------------ 79ms Oct 26 10:48:42 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 26 10:48:42 volumio volumio[804]: info: MPD Database updated - AlbumList cache refreshed Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: database Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: update Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: MPD Database updated - AlbumList cache refreshed Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: database Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: update Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: MPD Database updated - AlbumList cache refreshed Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: database Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: Oct 26 10:48:42 volumio volumio[804]: ---------------------------- MPD announces state update: update Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand status took 93 milliseconds Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand status took 92 milliseconds Oct 26 10:48:42 volumio volumio[804]: info: sendMpdCommand status took 93 milliseconds Oct 26 10:48:42 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: info: Command Router : Notfying DB Updatefalse Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::Close All Modals sent Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 278 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 278 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 277 milliseconds Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: info: Command Router : Notfying DB Updatefalse Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::Close All Modals sent Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 392 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 393 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 392 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 202 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 203 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: info: Command Router : Notfying DB Updatefalse Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::Close All Modals sent Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 499ms Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 498ms Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 497ms Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 497ms Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150274,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 524ms Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 523ms Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPlay Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::play index undefined Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::resume Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand play Oct 26 10:48:43 volumio volumio[804]: info: Oct 26 10:48:43 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand play took 5 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:43 volumio volumio[804]: info: Oct 26 10:48:43 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:43 volumio volumio[804]: info: Oct 26 10:48:43 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 13 milliseconds Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 26 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand status took 25 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151167,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 52ms Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 26 10:48:43 volumio volumio[804]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151167,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:48:43 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151167,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:43 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:48:43 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:43 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:43 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 100ms Oct 26 10:48:43 volumio volumio[804]: info: ------------------------------ 100ms Oct 26 10:48:43 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::servicePause Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePause Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pause Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 10:48:44 volumio volumio[804]: info: Oct 26 10:48:44 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand pause took 1 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:44 volumio volumio[804]: info: Oct 26 10:48:44 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:44 volumio volumio[804]: info: Oct 26 10:48:44 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand status took 2 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand status took 2 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand status took 1 milliseconds Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":151671,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:44 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":151671,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:44 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":151671,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:44 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Oct 26 10:48:44 volumio volumio[804]: info: ------------------------------ 41ms Oct 26 10:48:44 volumio volumio[804]: info: ------------------------------ 41ms Oct 26 10:48:44 volumio volumio[804]: info: ------------------------------ 41ms Oct 26 10:48:44 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPlay Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::play index undefined Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::resume Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand play Oct 26 10:48:44 volumio volumio[804]: info: Oct 26 10:48:44 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand play took 3 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:44 volumio volumio[804]: info: Oct 26 10:48:44 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:44 volumio volumio[804]: info: Oct 26 10:48:44 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand status took 3 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand status took 2 milliseconds Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 10:48:44 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:44 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152564,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:44 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152564,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:44 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:48:44 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152564,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hooked On A Feeling.mp3","artist":"Blue Swede","album":"Hooked On A Feeling","uri":"INTERNAL/Blue Swede/Hooked On A Feeling/Hooked On A Feeling.mp3","trackType":"mp3"} Oct 26 10:48:44 volumio volumio[804]: verbose: CURRENT POSITION 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:48:44 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:48:44 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:48:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:48:44 volumio volumio[804]: info: ------------------------------ 73ms Oct 26 10:48:44 volumio volumio[804]: info: ------------------------------ 73ms Oct 26 10:48:44 volumio volumio[804]: info: ------------------------------ 73ms Oct 26 10:48:44 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 26 10:48:49 volumio volumio[804]: info: MyVolumio login type: Token Oct 26 10:48:51 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:48:51 volumio volumio[804]: info: CURURI: music-library Oct 26 10:48:51 volumio volumio[804]: info: Preload queue cleared Oct 26 10:48:53 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:48:53 volumio volumio[804]: info: CURURI: music-library/INTERNAL Oct 26 10:48:53 volumio volumio[804]: info: Preload queue cleared Oct 26 10:49:00 volumio volumio[804]: info: CorePlayQueue::getTrack 71 Oct 26 10:49:00 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:00 volumio volumio[804]: info: Prefetching next song Oct 26 10:49:00 volumio volumio[804]: info: DOING PREFETCH IN MPD Oct 26 10:49:00 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a" Oct 26 10:49:00 volumio volumio[804]: info: Oct 26 10:49:00 volumio volumio[804]: ---------------------------- MPD announces system playlist update Oct 26 10:49:00 volumio volumio[804]: info: Ignoring MPD Status Update Oct 26 10:49:00 volumio volumio[804]: info: sendMpdCommand add "INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a" took 2 milliseconds Oct 26 10:49:00 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 26 10:49:00 volumio volumio[804]: info: Oct 26 10:49:00 volumio volumio[804]: ---------------------------- MPD announces system playlist update Oct 26 10:49:00 volumio volumio[804]: info: Ignoring MPD Status Update Oct 26 10:49:00 volumio volumio[804]: info: Oct 26 10:49:00 volumio volumio[804]: ---------------------------- MPD announces system playlist update Oct 26 10:49:00 volumio volumio[804]: info: Ignoring MPD Status Update Oct 26 10:49:00 volumio volumio[804]: info: ------------------------------ 4ms Oct 26 10:49:00 volumio volumio[804]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 26 10:49:00 volumio volumio[804]: info: ------------------------------ 3ms Oct 26 10:49:00 volumio volumio[804]: info: ------------------------------ 2ms Oct 26 10:49:01 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:49:01 volumio volumio[804]: info: CURURI: music-library Oct 26 10:49:01 volumio volumio[804]: info: Preload queue cleared Oct 26 10:49:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:49:02 volumio volumio[804]: info: CURURI: music-library/INTERNAL Oct 26 10:49:02 volumio volumio[804]: info: Preload queue cleared Oct 26 10:49:04 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Oct 26 10:49:04 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:05 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:05 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:05 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:49:05 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:05 volumio volumio[804]: info: Oct 26 10:49:05 volumio volumio[804]: ---------------------------- MPD announces system playlist update Oct 26 10:49:05 volumio volumio[804]: info: Ignoring MPD Status Update Oct 26 10:49:05 volumio volumio[804]: info: Oct 26 10:49:05 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:49:05 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:49:05 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:49:05 volumio volumio[804]: info: Oct 26 10:49:05 volumio volumio[804]: ---------------------------- MPD announces system playlist update Oct 26 10:49:05 volumio volumio[804]: info: Ignoring MPD Status Update Oct 26 10:49:05 volumio volumio[804]: info: Oct 26 10:49:05 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:49:05 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:49:05 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:49:05 volumio volumio[804]: info: Oct 26 10:49:05 volumio volumio[804]: ---------------------------- MPD announces system playlist update Oct 26 10:49:05 volumio volumio[804]: info: Ignoring MPD Status Update Oct 26 10:49:05 volumio volumio[804]: info: Oct 26 10:49:05 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:49:05 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:49:05 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:49:05 volumio volumio[804]: info: ------------------------------ 5ms Oct 26 10:49:06 volumio volumio[804]: info: Oct 26 10:49:06 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand status took 901 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 903ms Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand status took 903 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 905ms Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand status took 904 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand status took 6 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"I Want It All (Single Version)","artist":"Queen","album":"Queen: Absolute Greatest (Remastered)","uri":"INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a","trackType":"m4a"} Oct 26 10:49:06 volumio volumio[804]: verbose: CURRENT POSITION 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:49:06 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 941ms Oct 26 10:49:06 volumio volumio[804]: info: Oct 26 10:49:06 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:49:06 volumio volumio[804]: info: Oct 26 10:49:06 volumio volumio[804]: ---------------------------- MPD announces state update: player Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::getState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand status took 3 milliseconds Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseState Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":241,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"I Want It All (Single Version)","artist":"Queen","album":"Queen: Absolute Greatest (Remastered)","uri":"INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a","trackType":"m4a"} Oct 26 10:49:06 volumio volumio[804]: verbose: CURRENT POSITION 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:49:06 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331,"duration":241,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"I Want It All (Single Version)","artist":"Queen","album":"Queen: Absolute Greatest (Remastered)","uri":"INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a","trackType":"m4a"} Oct 26 10:49:06 volumio volumio[804]: verbose: CURRENT POSITION 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:49:06 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":241,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"I Want It All (Single Version)","artist":"Queen","album":"Queen: Absolute Greatest (Remastered)","uri":"INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a","trackType":"m4a"} Oct 26 10:49:06 volumio volumio[804]: verbose: CURRENT POSITION 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:49:06 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 1026ms Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 1026ms Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 127ms Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand playlistinfo took 98 milliseconds Oct 26 10:49:06 volumio volumio[804]: info: sendMpdCommand playlistinfo took 99 milliseconds Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:49:06 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":241,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"I Want It All (Single Version)","artist":"Queen","album":"Queen: Absolute Greatest (Remastered)","uri":"INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a","trackType":"m4a"} Oct 26 10:49:06 volumio volumio[804]: verbose: CURRENT POSITION 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:49:06 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: ControllerMpd::pushState Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":241,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"I Want It All (Single Version)","artist":"Queen","album":"Queen: Absolute Greatest (Remastered)","uri":"INTERNAL/Queen - Absolute Greatest (2009 Remaster)/1-05 I Want It All (Single Version).m4a","trackType":"m4a"} Oct 26 10:49:06 volumio volumio[804]: verbose: CURRENT POSITION 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Oct 26 10:49:06 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: CoreStateMachine::pushState Oct 26 10:49:06 volumio volumio[804]: info: CorePlayQueue::getTrack 382 Oct 26 10:49:06 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 145ms Oct 26 10:49:06 volumio volumio[804]: info: ------------------------------ 145ms Oct 26 10:49:19 volumio volumio[804]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 26 10:49:19 volumio volumio[804]: info: Retrying Login Due to Network Error Oct 26 10:49:20 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:49:20 volumio volumio[804]: info: CURURI: music-library Oct 26 10:49:20 volumio volumio[804]: info: Preload queue cleared Oct 26 10:49:22 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:49:22 volumio volumio[804]: info: CURURI: music-library/INTERNAL Oct 26 10:49:23 volumio volumio[804]: info: Preload queue cleared Oct 26 10:49:28 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 10:49:28 volumio volumio[804]: info: CURURI: music-library/INTERNAL Oct 26 10:49:29 volumio volumio[804]: info: Preload queue cleared Oct 26 10:49:29 volumio volumio[804]: info: MyVolumio login type: Token Oct 26 10:49:57 volumio volumio[804]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 10:49:57 volumio volumio[804]: Error: connect ETIMEDOUT 108.160.170.52:443 Oct 26 10:49:57 volumio volumio[804]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 26 10:49:57 volumio volumio[804]: errno: -110, Oct 26 10:49:57 volumio volumio[804]: code: 'ETIMEDOUT', Oct 26 10:49:57 volumio volumio[804]: syscall: 'connect', Oct 26 10:49:57 volumio volumio[804]: address: '108.160.170.52', Oct 26 10:49:57 volumio volumio[804]: port: 443 Oct 26 10:49:57 volumio volumio[804]: } Oct 26 10:49:57 volumio volumio[804]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 10:49:57 volumio sudo[4514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 10:48 Oct 26 10:49:57 volumio sudo[4514]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"