-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sun 2024-12-01 21:42:18 CST. -- Dec 01 21:41:10 volumio volumio[10772]: info: CoreCommandRouter::volumioGetQueue Dec 01 21:41:10 volumio volumio[10772]: info: CoreStateMachine::getQueue Dec 01 21:41:10 volumio volumio[10772]: info: CorePlayQueue::getQueue Dec 01 21:41:17 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Christmas%20Portrait%20(Special%20Edition%2FReissue)/4ec6561e-f95b-4bc6-8e8e-df9219ceb1da.jpg' Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPlay Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::play index 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::updateTrackBlock Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrackBlock Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 0 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::serviceStop Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 0 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::serviceStop Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::stop Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand stop took 8 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::startPlaybackTimer Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::clearAddPlayTracks NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3 Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 15 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand stop took 10 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 7 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand clear took 7 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3" Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: 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":"I Gotta Feeling","artist":"Black Eyed Peas","album":"Promo Only Mainstream Radio July 2009","uri":"NAS/36/无损/2022/Black Eyed Peas-I Gotta Feeling.flac","trackType":"flac"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: No code Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 21:41:23 volumio volumio[10772]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 73ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 64ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 61ms Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: error: updateQueue error: null Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 58ms Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3" took 52 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 10ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 9ms Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand play Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 13ms Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand play took 9 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 9ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 7ms Dec 01 21:41:23 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/8efed534-2d8b-4249-8c34-a1355ff011dd.jpg' Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 15 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 11 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 8 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 18 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 8 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:23 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:23 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 60ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 73ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 70ms Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPlay Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::play index 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::updateTrackBlock Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrackBlock Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::serviceStop Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::serviceStop Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::stop Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:23 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:23 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 105ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 109ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 106ms Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand stop took 47 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 9 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 5 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 3 Dec 01 21:41:23 volumio volumio[10772]: 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":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 3 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: 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":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: No code Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: 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":"I Just Fall In Love Again","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-I Just Fall In Love Again.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: No code Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::startPlaybackTimer Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::clearAddPlayTracks NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3 Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 76ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 72ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 71ms Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand stop took 14 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand clear took 3 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3" Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: error: updateQueue error: null Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 10ms Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3" took 9 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 8ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 6ms Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand play Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:23 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 11ms Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand play took 8 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 8ms Dec 01 21:41:23 volumio volumio[10772]: info: ------------------------------ 5ms Dec 01 21:41:23 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/GOLD%3A%20Greatest%20Hits/a17bb1b2-6a39-486f-96c1-246d66cbdbe4.jpg' Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 19 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 14 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 10 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: Dec 01 21:41:23 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 27 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 14 milliseconds Dec 01 21:41:23 volumio volumio[10772]: info: sendMpdCommand status took 10 milliseconds Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:23 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Merry Christmas, Darling","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:23 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Merry Christmas, Darling","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3","trackType":"mp3"} Dec 01 21:41:23 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:23 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:23 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:23 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:23 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Merry Christmas, Darling","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3","trackType":"mp3"} Dec 01 21:41:24 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:24 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: ------------------------------ 102ms Dec 01 21:41:24 volumio volumio[10772]: info: ------------------------------ 109ms Dec 01 21:41:24 volumio volumio[10772]: info: ------------------------------ 104ms Dec 01 21:41:24 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 01 21:41:24 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 01 21:41:24 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 01 21:41:24 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:24 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:24 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:24 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Merry Christmas, Darling","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3","trackType":"mp3"} Dec 01 21:41:24 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:24 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Merry Christmas, Darling","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3","trackType":"mp3"} Dec 01 21:41:24 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:24 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Merry Christmas, Darling","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Merry Christmas, Darling.mp3","trackType":"mp3"} Dec 01 21:41:24 volumio volumio[10772]: verbose: CURRENT POSITION 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:24 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:24 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:24 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:24 volumio volumio[10772]: info: ------------------------------ 153ms Dec 01 21:41:24 volumio volumio[10772]: info: ------------------------------ 136ms Dec 01 21:41:24 volumio volumio[10772]: info: ------------------------------ 132ms Dec 01 21:41:24 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/The%20Road%20to%20Yesterday%20(Live%201974)/0e0c3bce-83e1-4ffa-b33b-3c5a2b2e7415.jpg' Dec 01 21:41:24 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Yesterday%20Once%20More-Greatest%20Hits%201969-1983/b69b4d2a-153b-4e78-b94f-23788fcfe397.jpg' Dec 01 21:41:24 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Made%20In%20America/dad1c672-ec09-4b2b-92db-40ef8ba25c30.jpg' Dec 01 21:41:24 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/The%20Essential%20Collection%20(1965-1997)/aae1a5f1-991f-4645-b165-540a5bcd014e.jpg' Dec 01 21:41:25 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Singles%201969-1981/11b858b2-e1c5-4eb7-a948-93d9a9f31c46.jpg' Dec 01 21:41:25 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Carpenters%20Gold%20-%2035th%20Anniversary%20Edition/6f013b08-b340-49c5-99c6-ce692ae24149.jpg' Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPlay Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::play index 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::stop Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::updateTrackBlock Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrackBlock Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::serviceStop Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 4 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::serviceStop Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::stop Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand stop took 7 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::startPlaybackTimer Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::clearAddPlayTracks NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3 Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand stop took 3 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 8 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand clear took 7 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 6 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3" Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: 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} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: No code Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: 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} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: No code Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 52ms Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 51ms Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: error: updateQueue error: null Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 58ms Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3" took 55 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 9ms Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 7ms Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand play Dec 01 21:41:26 volumio volumio[10772]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 71ms Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 12ms Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand play took 7 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 5ms Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 4ms Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 5 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 3 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 9 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 7 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:26 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 40ms Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 47ms Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 5 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:26 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:26 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:26 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 113ms Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 108ms Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 107ms Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:26 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:26 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: ------------------------------ 92ms Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPlay Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::play index 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::stop Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::updateTrackBlock Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrackBlock Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::serviceStop Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreCommandRouter::serviceStop Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::stop Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand stop took 7 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: info: CoreStateMachine::startPlaybackTimer Dec 01 21:41:26 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::clearAddPlayTracks NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3 Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand stop took 2 milliseconds Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:26 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:26 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:26 volumio volumio[10772]: info: Dec 01 21:41:26 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:26 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 7 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand clear took 6 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 5 milliseconds Dec 01 21:41:26 volumio volumio[10772]: info: sendMpdCommand status took 3 milliseconds Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3" Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: 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} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: No code Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: 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} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: No code Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 44ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 44ms Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:27 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:27 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:27 volumio volumio[10772]: error: updateQueue error: null Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:27 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 52ms Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 49 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3" took 50 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 10ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 9ms Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand play Dec 01 21:41:27 volumio volumio[10772]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 63ms Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:27 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:27 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 14ms Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand play took 7 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 5ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 5ms Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand status took 7 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand status took 6 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:27 volumio volumio[10772]: info: Dec 01 21:41:27 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand status took 11 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand status took 6 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand status took 5 milliseconds Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:27 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:27 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 56ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 71ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 69ms Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 01 21:41:27 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 51 milliseconds Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:27 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:27 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ticket To Ride","artist":"Carpenters&Royal Philharmonic Orchestra","album":"Ticket To Ride / Yesterday Once More / Merry Christmas, Darling","uri":"NAS/36/无损/2022/Carpenters/Carpenters&Royal Philharmonic Orchestra-Ticket To Ride.mp3","trackType":"mp3"} Dec 01 21:41:27 volumio volumio[10772]: verbose: CURRENT POSITION 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:27 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:27 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:27 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 127ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 122ms Dec 01 21:41:27 volumio volumio[10772]: info: ------------------------------ 120ms Dec 01 21:41:30 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Yesterday%20Once%20More/6f36b8be-76d9-4c63-898e-fea532620d19.jpg' Dec 01 21:41:30 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/The%20Ultimate%20Collection/6c99e757-0400-4195-ae7a-b2325a0a3d05.jpg' Dec 01 21:41:31 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Ten%20songs%20for%20you/9216fec7-eff7-40b0-a95b-ae9ebea85f46.jpg' Dec 01 21:41:32 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Horizon/f79f34d0-24e7-418a-95dc-ccc65994fdb2.jpg' Dec 01 21:41:32 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Passage/51794690-decc-47cd-b4b8-32d210ef4108.jpg' Dec 01 21:41:32 volumio volumio[10772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Carpenters/489f70b5-89a8-4f9e-83a7-95e0b24e39e7.jpg' Dec 01 21:41:33 volumio volumio[10772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPlay Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::play index 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::updateTrackBlock Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrackBlock Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::serviceStop Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 8 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::serviceStop Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::stop Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand stop took 6 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::startPlaybackTimer Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::clearAddPlayTracks NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand stop took 2 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 7 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand clear took 6 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 5 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 3 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac" Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: 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} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: No code Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: 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} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: No code Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 51ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 50ms Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: error: updateQueue error: null Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 55ms Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac" took 53 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 7ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 5ms Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand play Dec 01 21:41:34 volumio volumio[10772]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 66ms Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 11ms Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand play took 8 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 7ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 5ms Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 8 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 10 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 20 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 14 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 12 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 67ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 72ms Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":320,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"586 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 146ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 133ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 130ms Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 74 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"598 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 101ms Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPlay Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::play index 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::updateTrackBlock Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrackBlock Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::serviceStop Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::serviceStop Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::stop Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand stop took 6 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::play index undefined Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::startPlaybackTimer Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::clearAddPlayTracks NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand stop took 3 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 8 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand clear took 8 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 6 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac" Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: 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} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: No code Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: 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} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: No code Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 54ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 53ms Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: error: updateQueue error: null Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 58ms Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 54 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand add "NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac" took 55 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 8ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 7ms Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand play Dec 01 21:41:34 volumio volumio[10772]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 80ms Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces system playlist update Dec 01 21:41:34 volumio volumio[10772]: info: Ignoring MPD Status Update Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 20ms Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand play took 9 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 9ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 7ms Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 12 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 10 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: Dec 01 21:41:34 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 20 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 16 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 9 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand status took 7 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus stop Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 64ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 69ms Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 40 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 01 21:41:34 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"602 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"598 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:34 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState stateService play Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus play Dec 01 21:41:34 volumio volumio[10772]: info: Received an update from plugin. extracting info from payload Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:34 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:34 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 143ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 140ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 131ms Dec 01 21:41:34 volumio volumio[10772]: info: ------------------------------ 128ms Dec 01 21:41:49 volumio volumio[10772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::volumioPause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::servicePause Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::servicePause Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::pause Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand pause Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand pause took 2 milliseconds Dec 01 21:41:59 volumio volumio[10772]: info: Dec 01 21:41:59 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:59 volumio volumio[10772]: info: Dec 01 21:41:59 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:59 volumio volumio[10772]: info: Dec 01 21:41:59 volumio volumio[10772]: ---------------------------- MPD announces state update: player Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::getState Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand status Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand status took 3 milliseconds Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand status took 4 milliseconds Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::parseState Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":25465,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:59 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::syncState stateService pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":25465,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:59 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::syncState stateService pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:59 volumio volumio[10772]: info: ------------------------------ 27ms Dec 01 21:41:59 volumio volumio[10772]: info: ------------------------------ 26ms Dec 01 21:41:59 volumio volumio[10772]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 01 21:41:59 volumio volumio[10772]: verbose: ControllerMpd::parseTrackInfo Dec 01 21:41:59 volumio volumio[10772]: info: ControllerMpd::pushState Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::servicePushState Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":25465,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"All You Get From Love Is A Love","artist":"Carpenters","album":null,"uri":"NAS/36/无损/2022/Carpenters/Carpenters-All You Get From Love Is A Love.flac","trackType":"flac"} Dec 01 21:41:59 volumio volumio[10772]: verbose: CURRENT POSITION 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::syncState stateService pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::syncState currentStatus pause Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::pushState Dec 01 21:41:59 volumio volumio[10772]: info: CorePlayQueue::getTrack 24 Dec 01 21:41:59 volumio volumio[10772]: info: CoreCommandRouter::volumioPushState Dec 01 21:41:59 volumio volumio[10772]: info: CoreStateMachine::stPlaybackTimer Dec 01 21:41:59 volumio volumio[10772]: info: ------------------------------ 35ms Dec 01 21:42:05 volumio volumio[10772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 01 21:42:17 volumio volumio[10772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:42:18 volumio volumio[10772]: Error: connect ETIMEDOUT 31.13.86.21:443 Dec 01 21:42:18 volumio volumio[10772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 01 21:42:18 volumio volumio[10772]: errno: -110, Dec 01 21:42:18 volumio volumio[10772]: code: 'ETIMEDOUT', Dec 01 21:42:18 volumio volumio[10772]: syscall: 'connect', Dec 01 21:42:18 volumio volumio[10772]: address: '31.13.86.21', Dec 01 21:42:18 volumio volumio[10772]: port: 443 Dec 01 21:42:18 volumio volumio[10772]: } Dec 01 21:42:18 volumio volumio[10772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:42:18 volumio sudo[11164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 21:41 Dec 01 21:42:18 volumio sudo[11164]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"