-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-03-14 15:44:08 CET. -- Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::volumioPause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::servicePause Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::servicePause Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::pause Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand pause Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand pause took 1 milliseconds Mar 14 15:43:12 volumio volumio[1007]: info: Mar 14 15:43:12 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:12 volumio volumio[1007]: info: Mar 14 15:43:12 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:12 volumio volumio[1007]: info: Mar 14 15:43:12 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 14 15:43:12 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:12 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":120576,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"936 Kbps","isStreaming":false,"title":"Un sabato italiano","artist":"Sergio Caputo","album":"Cocktail","uri":"USB/Seagate_Basic01/FLAC6 - Italiani/Cocktail/01 Un sabato italiano.flac","trackType":"flac"} Mar 14 15:43:12 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::syncState stateService pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":120576,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"936 Kbps","isStreaming":false,"title":"Un sabato italiano","artist":"Sergio Caputo","album":"Cocktail","uri":"USB/Seagate_Basic01/FLAC6 - Italiani/Cocktail/01 Un sabato italiano.flac","trackType":"flac"} Mar 14 15:43:12 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::syncState stateService pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:43:12 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":120576,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"936 Kbps","isStreaming":false,"title":"Un sabato italiano","artist":"Sergio Caputo","album":"Cocktail","uri":"USB/Seagate_Basic01/FLAC6 - Italiani/Cocktail/01 Un sabato italiano.flac","trackType":"flac"} Mar 14 15:43:12 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::syncState stateService pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus pause Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:12 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:12 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:12 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:43:12 volumio volumio[1007]: info: ------------------------------ 42ms Mar 14 15:43:12 volumio volumio[1007]: info: ------------------------------ 41ms Mar 14 15:43:12 volumio volumio[1007]: info: ------------------------------ 41ms Mar 14 15:43:21 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 14 15:43:21 volumio volumio[1007]: info: CURURI: music-library Mar 14 15:43:21 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:43:24 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 14 15:43:24 volumio volumio[1007]: info: CURURI: music-library/USB Mar 14 15:43:24 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:43:25 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 14 15:43:25 volumio volumio[1007]: info: CURURI: music-library/USB/Seagate_Basic01 Mar 14 15:43:25 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:43:33 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 14 15:43:33 volumio volumio[1007]: info: CURURI: music-library/USB/Seagate_Basic01/$RECYCLE.BIN Mar 14 15:43:33 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:43:43 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::ClearQueue Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::stop Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::serviceStop Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::serviceStop Mar 14 15:43:43 volumio volumio[1007]: info: ControllerMpd::stop Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::clearPlayQueue Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::saveQueue Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::addQueueItems Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::addQueueItems Mar 14 15:43:43 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:43:43 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000 Mar 14 15:43:43 volumio volumio[1007]: info: Exploding uri music-library/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000 in service mpd Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:43 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2FSeagate_Basic01%2F%24RECYCLE.BIN%2FS-1-5-21-3378383420-3613704245-734183786-1000%2F%24RYR2MDT.avi&metadata=false Mar 14 15:43:43 volumio volumio[1007]: info: URI /mnt/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand stop took 765 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::saveQueue Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPlay Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::play index 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::stop Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::play index undefined Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::startPlaybackTimer Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:43 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:43 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand status took 15 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand stop took 5 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand clear Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Mar 14 15:43:43 volumio volumio[1007]: info: Ignoring MPD Status Update Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand clear took 4 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi" Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:43 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: 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":"Un sabato italiano","artist":"Sergio Caputo","album":"Cocktail","uri":"USB/Seagate_Basic01/FLAC6 - Italiani/Cocktail/01 Un sabato italiano.flac","trackType":"flac"} Mar 14 15:43:43 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::syncState stateService stop Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus stop Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:43 volumio volumio[1007]: info: No code Mar 14 15:43:43 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:43 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:43 volumio volumio[1007]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 14 15:43:43 volumio volumio[1007]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 54ms Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 42ms Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 40ms Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Mar 14 15:43:43 volumio volumio[1007]: info: Ignoring MPD Status Update Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Mar 14 15:43:43 volumio volumio[1007]: info: Ignoring MPD Status Update Mar 14 15:43:43 volumio volumio[1007]: error: updateQueue error: null Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Mar 14 15:43:43 volumio volumio[1007]: info: Ignoring MPD Status Update Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 38ms Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand add "USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi" took 36 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 4ms Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 3ms Mar 14 15:43:43 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Mar 14 15:43:43 volumio volumio[1007]: info: Ignoring MPD Status Update Mar 14 15:43:43 volumio volumio[1007]: info: Mar 14 15:43:43 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Mar 14 15:43:43 volumio volumio[1007]: info: Ignoring MPD Status Update Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 10ms Mar 14 15:43:43 volumio volumio[1007]: info: sendMpdCommand play took 9 milliseconds Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 9ms Mar 14 15:43:43 volumio volumio[1007]: info: ------------------------------ 7ms Mar 14 15:43:44 volumio volumio[1007]: info: Mar 14 15:43:44 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:44 volumio volumio[1007]: info: Mar 14 15:43:44 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:44 volumio volumio[1007]: info: Mar 14 15:43:44 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand status took 19 milliseconds Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:44 volumio volumio[1007]: info: Mar 14 15:43:44 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand status took 22 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand status took 20 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:43:44 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus stop Mar 14 15:43:44 volumio volumio[1007]: info: ------------------------------ 32ms Mar 14 15:43:44 volumio volumio[1007]: info: Mar 14 15:43:44 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:44 volumio volumio[1007]: info: Mar 14 15:43:44 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand status took 13 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand status took 6 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand status took 5 milliseconds Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:43:44 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Mar 14 15:43:44 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:43:44 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Mar 14 15:43:44 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: ------------------------------ 109ms Mar 14 15:43:44 volumio volumio[1007]: info: ------------------------------ 108ms Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 69 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 66 milliseconds Mar 14 15:43:44 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 65 milliseconds Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:44 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:43:44 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Mar 14 15:43:44 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:43:44 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Mar 14 15:43:44 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:43:44 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Mar 14 15:43:44 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:43:44 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:43:44 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:43:44 volumio volumio[1007]: info: ------------------------------ 142ms Mar 14 15:43:44 volumio volumio[1007]: info: ------------------------------ 133ms Mar 14 15:43:44 volumio volumio[1007]: info: ------------------------------ 132ms Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::volumioPause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::servicePause Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::servicePause Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::pause Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand pause Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand pause took 2 milliseconds Mar 14 15:44:02 volumio volumio[1007]: info: Mar 14 15:44:02 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:44:02 volumio volumio[1007]: info: Mar 14 15:44:02 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:44:02 volumio volumio[1007]: info: Mar 14 15:44:02 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand status took 3 milliseconds Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::parseState Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 14 15:44:02 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:44:02 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19369,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:44:02 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::syncState stateService pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19369,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:44:02 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::syncState stateService pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:44:02 volumio volumio[1007]: info: ControllerMpd::pushState Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19369,"duration":489,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"$RYR2MDT.avi","artist":null,"album":null,"uri":"USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-3378383420-3613704245-734183786-1000/$RYR2MDT.avi","trackType":"avi"} Mar 14 15:44:02 volumio volumio[1007]: verbose: CURRENT POSITION 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::syncState stateService pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus pause Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:44:02 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:02 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:44:02 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:44:02 volumio volumio[1007]: info: ------------------------------ 43ms Mar 14 15:44:02 volumio volumio[1007]: info: ------------------------------ 43ms Mar 14 15:44:02 volumio volumio[1007]: info: ------------------------------ 42ms Mar 14 15:44:07 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:44:07 volumio volumio[1007]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::ClearQueue Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::stop Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Mar 14 15:44:07 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::pushState Mar 14 15:44:07 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:07 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 15:44:07 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::serviceStop Mar 14 15:44:07 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 14 15:44:07 volumio volumio[1007]: info: CoreCommandRouter::serviceStop Mar 14 15:44:07 volumio volumio[1007]: info: ControllerMpd::stop Mar 14 15:44:07 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Mar 14 15:44:07 volumio volumio[1007]: info: CorePlayQueue::clearPlayQueue Mar 14 15:44:07 volumio volumio[1007]: info: CorePlayQueue::saveQueue Mar 14 15:44:07 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Mar 14 15:44:07 volumio volumio[1007]: info: CoreStateMachine::addQueueItems Mar 14 15:44:07 volumio volumio[1007]: info: CorePlayQueue::addQueueItems Mar 14 15:44:07 volumio volumio[1007]: info: Preload queue cleared Mar 14 15:44:07 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-319739666-3037389145-110245364-1001 Mar 14 15:44:07 volumio volumio[1007]: info: Exploding uri music-library/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-319739666-3037389145-110245364-1001 in service mpd Mar 14 15:44:07 volumio volumio[1007]: info: Mar 14 15:44:07 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:44:07 volumio volumio[1007]: info: sendMpdCommand stop took 209 milliseconds Mar 14 15:44:07 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:44:07 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:44:07 volumio volumio[1007]: info: Mar 14 15:44:07 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:44:07 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:44:07 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:44:07 volumio volumio[1007]: info: Mar 14 15:44:07 volumio volumio[1007]: ---------------------------- MPD announces state update: player Mar 14 15:44:07 volumio volumio[1007]: info: ControllerMpd::getState Mar 14 15:44:07 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Mar 14 15:44:07 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=288&web=Raffaele%20Casarano%3B%20Locomotive/Legend%20-%20featuring%20paolo%20fresu/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Basic01%2F%24RECYCLE.BIN%2FS-1-5-21-319739666-3037389145-110245364-1001%2F%24R1QFA6Y.zip&metadata=false Mar 14 15:44:07 volumio volumio[1007]: info: URI /mnt/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-319739666-3037389145-110245364-1001/$R1QFA6Y.zip Mar 14 15:44:07 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=288&web=Raffaele%20Casarano%3B%20Locomotive/Legend%20-%20featuring%20paolo%20fresu/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Basic01%2F%24RECYCLE.BIN%2FS-1-5-21-319739666-3037389145-110245364-1001%2F%24R1QFA6Y.zip&metadata=false Mar 14 15:44:07 volumio volumio[1007]: info: URI /mnt/USB/Seagate_Basic01/$RECYCLE.BIN/S-1-5-21-319739666-3037389145-110245364-1001/$R1QFA6Y.zip Mar 14 15:44:07 volumio volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 15:44:07 volumio volumio[1007]: Error: Unable to resolve or reject the same promise twice Mar 14 15:44:07 volumio volumio[1007]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 14 15:44:07 volumio volumio[1007]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Mar 14 15:44:07 volumio volumio[1007]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 14 15:44:07 volumio volumio[1007]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 14 15:44:07 volumio volumio[1007]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 14 15:44:07 volumio volumio[1007]: at Socket.emit (events.js:315:20) Mar 14 15:44:07 volumio volumio[1007]: at addChunk (internal/streams/readable.js:309:12) Mar 14 15:44:07 volumio volumio[1007]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 14 15:44:07 volumio volumio[1007]: at Socket.Readable.push (internal/streams/readable.js:223:10) Mar 14 15:44:07 volumio volumio[1007]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 14 15:44:07 volumio volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 15:44:08 volumio sudo[10141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-14 15:43 Mar 14 15:44:08 volumio sudo[10141]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"