-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Sun 2026-01-11 15:04:50 EST. -- Jan 11 15:03:12 tinker volumio[1113]: Searching plugin music_service/mpd Jan 11 15:03:12 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 11 15:03:12 tinker volumio[1113]: info: All search sources collected, pushing search results Jan 11 15:03:44 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 15:03:44 tinker volumio[1113]: info: CURURI: artists:// Jan 11 15:03:44 tinker volumio[1113]: info: Preload queue cleared Jan 11 15:04:28 tinker volumio[1113]: info: CoreCommandRouter::volumioGetState Jan 11 15:04:28 tinker volumio[1113]: info: CorePlayQueue::getTrack 2 Jan 11 15:04:38 tinker volumio[1113]: info: CoreCommandRouter::volumioNext Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::next Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::stop Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::stPlaybackTimer Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:38 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:38 tinker volumio[1113]: info: CorePlayQueue::getTrack 2 Jan 11 15:04:38 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:38 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::serviceStop Jan 11 15:04:38 tinker volumio[1113]: info: CorePlayQueue::getTrack 2 Jan 11 15:04:38 tinker volumio[1113]: info: CoreCommandRouter::serviceStop Jan 11 15:04:38 tinker volumio[1113]: info: ControllerMpd::stop Jan 11 15:04:38 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:38 tinker volumio[1113]: info: sendMpdCommand stop took 25 milliseconds Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::play index undefined Jan 11 15:04:38 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand stop took 11 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 7 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 5 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 4 milliseconds Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:39 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand clear took 13 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac" Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:04:39 tinker volumio[1113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:04:39 tinker volumio[1113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 35ms Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 32ms Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 31ms Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:39 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:39 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:39 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:39 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 19ms Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac" took 16 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 7ms Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 6ms Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:39 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:39 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 21ms Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand play took 19 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 19ms Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 19ms Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 187 milliseconds Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 189 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 185 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:39 tinker volumio[1113]: verbose: CURRENT POSITION 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 206ms Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: Jan 11 15:04:39 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 16 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 3 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand status took 2 milliseconds Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:39 tinker volumio[1113]: verbose: CURRENT POSITION 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:39 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:39 tinker volumio[1113]: verbose: CURRENT POSITION 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:39 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 245ms Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 244ms Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 11 15:04:39 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:39 tinker volumio[1113]: verbose: CURRENT POSITION 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:39 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:39 tinker volumio[1113]: verbose: CURRENT POSITION 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:39 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:39 tinker volumio[1113]: verbose: CURRENT POSITION 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:39 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:39 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:39 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 129ms Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 116ms Jan 11 15:04:39 tinker volumio[1113]: info: ------------------------------ 116ms Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioNext Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::next Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::stop Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::stPlaybackTimer Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::serviceStop Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 3 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::serviceStop Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::stop Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand stop took 29 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::play index undefined Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 11 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand stop took 7 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 3 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 3 milliseconds Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:42 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand clear took 4 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac" Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairweather","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/04_Fairweather.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService stop Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: No code Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:04:42 tinker volumio[1113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 50ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 33ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 33ms Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:42 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:42 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:42 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:42 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 35ms Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac" took 33 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 11ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 10ms Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:42 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:42 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 26ms Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand play took 23 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 29ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 25ms Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 178 milliseconds Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 169 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 166 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Years Day","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 193ms Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: Jan 11 15:04:42 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 12 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 8 milliseconds Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand status took 7 milliseconds Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Years Day","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:42 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"549 Kbps","isStreaming":false,"title":"New Years Day","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:42 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 232ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 224ms Jan 11 15:04:42 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 11 15:04:42 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"574 Kbps","isStreaming":false,"title":"New Years Day","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:42 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"574 Kbps","isStreaming":false,"title":"New Years Day","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:42 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"574 Kbps","isStreaming":false,"title":"New Years Day","artist":"Allison Moorer","album":"Getting Somewhere","uri":"USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/05_New_Years_Day.flac","trackType":"flac"} Jan 11 15:04:42 tinker volumio[1113]: verbose: CURRENT POSITION 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState stateService play Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:04:42 tinker volumio[1113]: info: Received an update from plugin. extracting info from payload Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:42 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:42 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 130ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 126ms Jan 11 15:04:42 tinker volumio[1113]: info: ------------------------------ 126ms Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioNext Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::next Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::stPlaybackTimer Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::serviceStop Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 4 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::serviceStop Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::stop Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioNext Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::next Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::play index undefined Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 5 Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 5 Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/06_How_She_Does_It.flac Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand stop took 29 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand stop took 24 milliseconds Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::play index undefined Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/07_Where_You_Are.flac Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::updateTrackBlock Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrackBlock Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand status took 17 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand clear took 16 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand stop took 12 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand status took 10 milliseconds Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/06_How_She_Does_It.flac" Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 11 15:04:49 tinker volumio[1113]: verbose: CURRENT POSITION 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::syncState stateService stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: info: No code Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 55ms Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:49 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand status took 59 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 54ms Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand playlistinfo took 51 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/06_How_She_Does_It.flac" took 52 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand clear took 51 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 9ms Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 9ms Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::parseState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/07_Where_You_Are.flac" Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::servicePushState Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 11 15:04:49 tinker volumio[1113]: verbose: CURRENT POSITION 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::syncState stateService stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: info: No code Jan 11 15:04:49 tinker volumio[1113]: info: CoreStateMachine::pushState Jan 11 15:04:49 tinker volumio[1113]: info: CorePlayQueue::getTrack 6 Jan 11 15:04:49 tinker volumio[1113]: info: CoreCommandRouter::volumioPushState Jan 11 15:04:49 tinker volumio[1113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 98ms Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 109ms Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:49 tinker volumio[1113]: error: updateQueue error: null Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 100ms Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 100ms Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand play took 97 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand add "USB/USB_DISK/music/flac/Allison_Moorer_-_Getting_Somewhere/07_Where_You_Are.flac" took 96 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 62ms Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 61ms Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces system playlist update Jan 11 15:04:49 tinker volumio[1113]: info: Ignoring MPD Status Update Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 24ms Jan 11 15:04:49 tinker volumio[1113]: info: sendMpdCommand play took 16 milliseconds Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 16ms Jan 11 15:04:49 tinker volumio[1113]: info: ------------------------------ 14ms Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:49 tinker volumio[1113]: info: Jan 11 15:04:49 tinker volumio[1113]: ---------------------------- MPD announces state update: player Jan 11 15:04:49 tinker volumio[1113]: info: ControllerMpd::getState Jan 11 15:04:49 tinker volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:04:49 tinker volumio[1113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:04:49 tinker volumio[1113]: TypeError: Cannot read property 'slice' of null Jan 11 15:04:49 tinker volumio[1113]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 11 15:04:49 tinker volumio[1113]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 11 15:04:49 tinker volumio[1113]: at Object.onceWrapper (events.js:520:26) Jan 11 15:04:49 tinker volumio[1113]: at ClientRequest.emit (events.js:400:28) Jan 11 15:04:49 tinker volumio[1113]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Jan 11 15:04:49 tinker volumio[1113]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Jan 11 15:04:49 tinker volumio[1113]: at TLSSocket.socketOnData (_http_client.js:515:22) Jan 11 15:04:49 tinker volumio[1113]: at TLSSocket.emit (events.js:400:28) Jan 11 15:04:49 tinker volumio[1113]: at addChunk (internal/streams/readable.js:293:12) Jan 11 15:04:49 tinker volumio[1113]: at readableAddChunk (internal/streams/readable.js:267:9) Jan 11 15:04:49 tinker volumio[1113]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Jan 11 15:04:49 tinker volumio[1113]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Jan 11 15:04:49 tinker volumio[1113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:04:50 tinker sudo[5469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 15:03 Jan 11 15:04:50 tinker sudo[5469]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"