-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sat 2025-12-13 11:11:14 MSK. -- Dec 13 11:10:04 rivo volumio[3184]: info: Preload queue cleared Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::ClearQueue Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::stop Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::stPlaybackTimer Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::updateTrackBlock Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrackBlock Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::serviceStop Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::serviceStop Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::stop Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::clearPlayQueue Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::saveQueue Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushQueue Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::addQueueItems Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::addQueueItems Dec 13 11:10:04 rivo volumio[3184]: info: Preload queue cleared Dec 13 11:10:04 rivo volumio[3184]: info: Adding Item to queue: artists://Chris%20Rea Dec 13 11:10:04 rivo volumio[3184]: info: Exploding uri artists://Chris%20Rea in service mpd Dec 13 11:10:04 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushQueue Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::saveQueue Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::updateTrackBlock Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrackBlock Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPlay Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::play index 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::stop Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::play index undefined Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::startPlaybackTimer Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3 Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand stop took 74 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand stop took 16 milliseconds Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:04 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 11 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand clear took 10 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 7 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 5 milliseconds Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3" Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: 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 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService stop Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus stop Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: No code Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: 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 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService stop Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus stop Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: No code Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 84ms Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 83ms Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:04 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:04 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:04 rivo volumio[3184]: error: updateQueue error: null Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:04 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 97ms Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 93 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand add "USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3" took 92 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 16ms Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 11ms Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand play Dec 13 11:10:04 rivo volumio[3184]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 116ms Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:04 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:04 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:04 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:04 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:04 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:04 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:04 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 63ms Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand play took 57 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 55ms Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 54ms Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Dec 13 11:10:04 rivo volumio[3184]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Dec 13 11:10:04 rivo volumio[3184]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Dec 13 11:10:04 rivo volumio[3184]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Dec 13 11:10:04 rivo volumio[3184]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Dec 13 11:10:04 rivo volumio[3184]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Dec 13 11:10:04 rivo volumio[3184]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 13 11:10:04 rivo volumio[3184]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 13 11:10:04 rivo volumio[3184]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Dec 13 11:10:04 rivo volumio[3184]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Dec 13 11:10:04 rivo volumio[3184]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Dec 13 11:10:04 rivo volumio[3184]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Dec 13 11:10:04 rivo volumio[3184]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Dec 13 11:10:04 rivo volumio[3184]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Dec 13 11:10:04 rivo volumio[3184]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 13 11:10:04 rivo volumio[3184]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 92 milliseconds Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 96 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 89 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Memory Of A Good Friend","artist":"Chris Rea","album":"Chris Rea -the Best-","uri":"USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3","trackType":"mp3"} Dec 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus stop Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 117ms Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: Dec 13 11:10:04 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 22 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 7 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand status took 5 milliseconds Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Memory Of A Good Friend","artist":"Chris Rea","album":"Chris Rea -the Best-","uri":"USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3","trackType":"mp3"} Dec 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:04 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Memory Of A Good Friend","artist":"Chris Rea","album":"Chris Rea -the Best-","uri":"USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3","trackType":"mp3"} Dec 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:04 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 256ms Dec 13 11:10:04 rivo volumio[3184]: info: ------------------------------ 255ms Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 157 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 155 milliseconds Dec 13 11:10:04 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 156 milliseconds Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:04 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Memory Of A Good Friend","artist":"Chris Rea","album":"Chris Rea -the Best-","uri":"USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3","trackType":"mp3"} Dec 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:04 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Memory Of A Good Friend","artist":"Chris Rea","album":"Chris Rea -the Best-","uri":"USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3","trackType":"mp3"} Dec 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:04 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:04 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:04 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:04 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Memory Of A Good Friend","artist":"Chris Rea","album":"Chris Rea -the Best-","uri":"USB/KINGSTON/chris-rea-the-memory-of-a-good-friend-the-best-of-chris-rea_(mp3CC.biz).mp3","trackType":"mp3"} Dec 13 11:10:04 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:04 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:04 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:05 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:05 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:05 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:05 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:05 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:05 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:05 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:05 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:05 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:05 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:05 rivo volumio[3184]: info: ------------------------------ 269ms Dec 13 11:10:05 rivo volumio[3184]: info: ------------------------------ 253ms Dec 13 11:10:05 rivo volumio[3184]: info: ------------------------------ 251ms Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:05 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:07 rivo volumio[3184]: info: Executing endpoint metavolumio Dec 13 11:10:07 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 13 11:10:07 rivo volumio[3184]: info: Executing endpoint metavolumio Dec 13 11:10:07 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 13 11:10:07 rivo volumio[3184]: info: Executing endpoint metavolumio Dec 13 11:10:07 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 13 11:10:07 rivo volumio[3184]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Dec 13 11:10:07 rivo volumio[3184]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Dec 13 11:10:07 rivo volumio[3184]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Dec 13 11:10:07 rivo volumio[3184]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Dec 13 11:10:07 rivo volumio[3184]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Dec 13 11:10:07 rivo volumio[3184]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Dec 13 11:10:07 rivo volumio[3184]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 13 11:10:07 rivo volumio[3184]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 13 11:10:09 rivo volumio[3184]: info: Preload queue cleared Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::ClearQueue Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::stop Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::stPlaybackTimer Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::updateTrackBlock Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrackBlock Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::serviceStop Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::serviceStop Dec 13 11:10:09 rivo volumio[3184]: info: ControllerMpd::stop Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::clearPlayQueue Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::saveQueue Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushQueue Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::addQueueItems Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::addQueueItems Dec 13 11:10:09 rivo volumio[3184]: info: Preload queue cleared Dec 13 11:10:09 rivo volumio[3184]: info: Adding Item to queue: artists://CHRIS%20REA Dec 13 11:10:09 rivo volumio[3184]: info: Using cached record of: artists://CHRIS%20REA Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushQueue Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::saveQueue Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::updateTrackBlock Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrackBlock Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPlay Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::play index 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::stop Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::play index undefined Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::startPlaybackTimer Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3 Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 11:10:09 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:09 rivo volumio[3184]: info: Dec 13 11:10:09 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:09 rivo volumio[3184]: info: sendMpdCommand stop took 65 milliseconds Dec 13 11:10:09 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:09 rivo volumio[3184]: info: sendMpdCommand stop took 28 milliseconds Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 11:10:09 rivo volumio[3184]: info: Dec 13 11:10:09 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:09 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:09 rivo volumio[3184]: info: Dec 13 11:10:09 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:09 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:09 rivo volumio[3184]: info: Dec 13 11:10:09 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:09 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:09 rivo volumio[3184]: info: sendMpdCommand status took 11 milliseconds Dec 13 11:10:09 rivo volumio[3184]: info: sendMpdCommand clear took 10 milliseconds Dec 13 11:10:09 rivo volumio[3184]: info: sendMpdCommand status took 9 milliseconds Dec 13 11:10:09 rivo volumio[3184]: info: sendMpdCommand status took 7 milliseconds Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3" Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:09 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:09 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: 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 13 11:10:09 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::syncState stateService stop Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus stop Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: No code Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: 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 13 11:10:09 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::syncState stateService stop Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus stop Dec 13 11:10:09 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:09 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:09 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: No code Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 91ms Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 91ms Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:10 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:10 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:10 rivo volumio[3184]: error: updateQueue error: null Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:10 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 96ms Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 92 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand add "USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3" took 91 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 10ms Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 8ms Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand play Dec 13 11:10:10 rivo volumio[3184]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 115ms Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:10 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces system playlist update Dec 13 11:10:10 rivo volumio[3184]: info: Ignoring MPD Status Update Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 79ms Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand play took 72 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 70ms Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 68ms Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:10 rivo volumio[3184]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Dec 13 11:10:10 rivo volumio[3184]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Dec 13 11:10:10 rivo volumio[3184]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Dec 13 11:10:10 rivo volumio[3184]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Dec 13 11:10:10 rivo volumio[3184]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Dec 13 11:10:10 rivo volumio[3184]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Dec 13 11:10:10 rivo volumio[3184]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 13 11:10:10 rivo volumio[3184]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand status took 99 milliseconds Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:10 rivo volumio[3184]: info: Dec 13 11:10:10 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand status took 111 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand status took 109 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand status took 8 milliseconds Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:10 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus stop Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 140ms Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand status took 25 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand status took 24 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:10 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:10 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:10 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:10 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:10 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:10 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 294ms Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 293ms Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 193ms Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 151 milliseconds Dec 13 11:10:10 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 151 milliseconds Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:10 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:10 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:10 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:10 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:10 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:10 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:10 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 341ms Dec 13 11:10:10 rivo volumio[3184]: info: ------------------------------ 342ms Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:10 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:12 rivo volumio[3184]: info: Executing endpoint metavolumio Dec 13 11:10:12 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 13 11:10:12 rivo volumio[3184]: info: Executing endpoint metavolumio Dec 13 11:10:12 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 13 11:10:12 rivo volumio[3184]: info: Executing endpoint metavolumio Dec 13 11:10:12 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioSeek Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::seek Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: TRACKBLOCK {"uri":"music-library/USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","service":"mpd","name":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=55&web=CHRIS%20REA/Romantic%20Collection%60Nostalgi%60/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON&metadata=false","duration":288,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::startPlaybackTimer Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::seek Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: error: null Dec 13 11:10:13 rivo volumio[3184]: info: Dec 13 11:10:13 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:13 rivo volumio[3184]: info: Dec 13 11:10:13 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:13 rivo volumio[3184]: info: Dec 13 11:10:13 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:13 rivo volumio[3184]: info: sendMpdCommand status took 14 milliseconds Dec 13 11:10:13 rivo volumio[3184]: info: sendMpdCommand status took 12 milliseconds Dec 13 11:10:13 rivo volumio[3184]: info: sendMpdCommand status took 10 milliseconds Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:13 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 13 11:10:13 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 13 11:10:13 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:13 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:13 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:13 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:13 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:13 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:13 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::syncState stateService play Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus play Dec 13 11:10:13 rivo volumio[3184]: info: Received an update from plugin. extracting info from payload Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:13 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:13 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:13 rivo volumio[3184]: info: ------------------------------ 121ms Dec 13 11:10:13 rivo volumio[3184]: info: ------------------------------ 121ms Dec 13 11:10:13 rivo volumio[3184]: info: ------------------------------ 122ms Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:13 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:14 rivo volumio[3184]: error: Failed request for metavolumio API Dec 13 11:10:14 rivo volumio[3184]: error: Failed request for metavolumio API Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioPause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::stPlaybackTimer Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::servicePause Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::servicePause Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::pause Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand pause Dec 13 11:10:42 rivo volumio[3184]: info: Dec 13 11:10:42 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand pause took 5 milliseconds Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:42 rivo volumio[3184]: info: Dec 13 11:10:42 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:42 rivo volumio[3184]: info: Dec 13 11:10:42 rivo volumio[3184]: ---------------------------- MPD announces state update: player Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::getState Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand status Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand status took 9 milliseconds Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand status took 5 milliseconds Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand status took 2 milliseconds Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::parseState Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 13 11:10:42 rivo volumio[3184]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:42 rivo volumio[3184]: verbose: ControllerMpd::parseTrackInfo Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":129022,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:42 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::syncState stateService pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::stPlaybackTimer Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":129022,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:42 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::syncState stateService pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::stPlaybackTimer Dec 13 11:10:42 rivo volumio[3184]: info: ControllerMpd::pushState Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::servicePushState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":129022,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CHRIS REA-BLUE CAFE","artist":"CHRIS REA","album":"Romantic Collection`Nostalgi`","uri":"USB/KINGSTON/chris-rea-chris-rea-blue-cafe_(mp3CC.biz) (1).mp3","trackType":"mp3"} Dec 13 11:10:42 rivo volumio[3184]: verbose: CURRENT POSITION 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::syncState stateService pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::syncState currentStatus pause Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::pushState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioPushState Dec 13 11:10:42 rivo volumio[3184]: info: CoreCommandRouter::volumioGetState Dec 13 11:10:42 rivo volumio[3184]: info: CorePlayQueue::getTrack 0 Dec 13 11:10:42 rivo volumio[3184]: info: CoreStateMachine::stPlaybackTimer Dec 13 11:10:42 rivo volumio[3184]: info: ------------------------------ 57ms Dec 13 11:10:42 rivo volumio[3184]: info: ------------------------------ 55ms Dec 13 11:10:42 rivo volumio[3184]: info: ------------------------------ 52ms Dec 13 11:10:42 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:42 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:10:42 rivo volumio[3184]: info: Updating RAAT Signal Path Dec 13 11:11:14 rivo volumio[3184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 11:11:14 rivo volumio[3184]: Error: connect ETIMEDOUT 2.17.251.68:443 Dec 13 11:11:14 rivo volumio[3184]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 13 11:11:14 rivo volumio[3184]: errno: -110, Dec 13 11:11:14 rivo volumio[3184]: code: 'ETIMEDOUT', Dec 13 11:11:14 rivo volumio[3184]: syscall: 'connect', Dec 13 11:11:14 rivo volumio[3184]: address: '2.17.251.68', Dec 13 11:11:14 rivo volumio[3184]: port: 443 Dec 13 11:11:14 rivo volumio[3184]: } Dec 13 11:11:14 rivo volumio[3184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 11:11:14 rivo sudo[5528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-13 11:10 Dec 13 11:11:14 rivo sudo[5528]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 08:04:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="bbdd85796562d864ebadb328c8f41ca1"