-- Logs begin at Wed 2025-07-02 14:55:44 UTC, end at Fri 2025-07-18 09:30:35 UTC. -- Jul 18 09:29:00 rivoplus volumio[3183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 18 09:29:01 rivoplus volumio[3183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 18 09:29:04 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:29:04 rivoplus volumio[3183]: info: CURURI: albums://Sarah%20McLachlan/Freds%20Tuning%20Disc%20Disc%203%20%22Demo%20%26%20Eval%22 Jul 18 09:29:04 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:04 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:04 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif in service mpd Jul 18 09:29:04 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=Sarah%20McLachlan/Freds%20Tuning%20Disc%20Disc%203%20%22Demo%20%26%20Eval%22/extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2FARC%203%20Demo%20%26%20Evaluaton%2F03%20Angel%201.aif&metadata=false Jul 18 09:29:04 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:04 rivoplus volumio[3183]: info: Executing endpoint getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: Executing endpoint getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: Executing endpoint getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: Executing endpoint getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: Executing endpoint getSimilarAlbums Jul 18 09:29:04 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 18 09:29:05 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:05 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:07 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:07 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:07 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::ClearQueue Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::stPlaybackTimer Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::serviceStop Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::serviceStop Jul 18 09:29:07 rivoplus volumio[3183]: info: ControllerMpd::stop Jul 18 09:29:07 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::clearPlayQueue Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::addQueueItems Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::addQueueItems Jul 18 09:29:07 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:07 rivoplus volumio[3183]: info: Adding Item to queue: music-library/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:07 rivoplus volumio[3183]: info: Using cached record of: music-library/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPlay Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::play index 0 Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::play index undefined Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreStateMachine::startPlaybackTimer Jul 18 09:29:07 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:29:07 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 18 09:29:07 rivoplus volumio[3183]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:07 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:29:07 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:29:07 rivoplus volumio[3183]: info: Jul 18 09:29:07 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:29:07 rivoplus volumio[3183]: info: sendMpdCommand stop took 52 milliseconds Jul 18 09:29:07 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:29:07 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:29:07 rivoplus volumio[3183]: info: sendMpdCommand stop took 21 milliseconds Jul 18 09:29:07 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 09:29:08 rivoplus volumio[3183]: info: Jul 18 09:29:08 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:29:08 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand status took 6 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand clear took 6 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif" Jul 18 09:29:08 rivoplus volumio[3183]: error: updateQueue error: null Jul 18 09:29:08 rivoplus volumio[3183]: info: Jul 18 09:29:08 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:29:08 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:29:08 rivoplus volumio[3183]: info: ------------------------------ 11ms Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand add "INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif" took 7 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand play Jul 18 09:29:08 rivoplus volumio[3183]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 09:29:08 rivoplus volumio[3183]: info: ------------------------------ 22ms Jul 18 09:29:08 rivoplus volumio[3183]: info: ------------------------------ 9ms Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand play took 5 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: info: Jul 18 09:29:08 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:29:08 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:29:08 rivoplus volumio[3183]: info: Jul 18 09:29:08 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand status took 7 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand status took 3 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:29:08 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Angel","artist":"Sarah McLachlan","album":"Freds Tuning Disc Disc 3 \"Demo & Eval\"","uri":"INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif","trackType":"aif"} Jul 18 09:29:08 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus stop Jul 18 09:29:08 rivoplus volumio[3183]: info: ------------------------------ 22ms Jul 18 09:29:08 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:29:08 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Angel","artist":"Sarah McLachlan","album":"Freds Tuning Disc Disc 3 \"Demo & Eval\"","uri":"INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif","trackType":"aif"} Jul 18 09:29:08 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:29:08 rivoplus volumio[3183]: info: Received an update from plugin. extracting info from payload Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: ------------------------------ 47ms Jul 18 09:29:08 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:29:08 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:29:08 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:29:08 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:29:08 rivoplus volumio[3183]: info: MCU Signalled Playback Inactive Jul 18 09:29:08 rivoplus volumio[3183]: info: MCU Signalled Playback Active Jul 18 09:29:08 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:08 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:08 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:08 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::ClearQueue Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::stPlaybackTimer Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::serviceStop Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::serviceStop Jul 18 09:29:08 rivoplus volumio[3183]: info: ControllerMpd::stop Jul 18 09:29:08 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::clearPlayQueue Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:29:08 rivoplus volumio[3183]: info: CoreStateMachine::addQueueItems Jul 18 09:29:08 rivoplus volumio[3183]: info: CorePlayQueue::addQueueItems Jul 18 09:29:08 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:08 rivoplus volumio[3183]: info: Adding Item to queue: music-library/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:08 rivoplus volumio[3183]: info: Using cached record of: music-library/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPlay Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::play index 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::play index undefined Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::startPlaybackTimer Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:29:09 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:29:09 rivoplus volumio[3183]: info: Jul 18 09:29:09 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand stop took 55 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand stop took 25 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 09:29:09 rivoplus volumio[3183]: info: Jul 18 09:29:09 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:29:09 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand status took 4 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand clear took 4 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif" Jul 18 09:29:09 rivoplus volumio[3183]: error: updateQueue error: null Jul 18 09:29:09 rivoplus volumio[3183]: info: Jul 18 09:29:09 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:29:09 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:29:09 rivoplus volumio[3183]: info: ------------------------------ 8ms Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand add "INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif" took 6 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand play Jul 18 09:29:09 rivoplus volumio[3183]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 09:29:09 rivoplus volumio[3183]: info: ------------------------------ 19ms Jul 18 09:29:09 rivoplus volumio[3183]: info: ------------------------------ 7ms Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand play took 4 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: info: Jul 18 09:29:09 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:29:09 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand status took 15 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:29:09 rivoplus volumio[3183]: info: Jul 18 09:29:09 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:29:09 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:29:09 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Angel","artist":"Sarah McLachlan","album":"Freds Tuning Disc Disc 3 \"Demo & Eval\"","uri":"INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif","trackType":"aif"} Jul 18 09:29:09 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus stop Jul 18 09:29:09 rivoplus volumio[3183]: info: ------------------------------ 25ms Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand status took 6 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:29:09 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 18 09:29:09 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:29:09 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Angel","artist":"Sarah McLachlan","album":"Freds Tuning Disc Disc 3 \"Demo & Eval\"","uri":"INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif","trackType":"aif"} Jul 18 09:29:09 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:29:09 rivoplus volumio[3183]: info: Received an update from plugin. extracting info from payload Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:09 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:09 rivoplus volumio[3183]: info: ------------------------------ 40ms Jul 18 09:29:09 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:29:09 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:29:09 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:29:09 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:29:09 rivoplus volumio[3183]: info: MCU Signalled Playback Inactive Jul 18 09:29:09 rivoplus volumio[3183]: info: MCU Signalled Playback Active Jul 18 09:29:09 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:09 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:11 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:11 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:11 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:11 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:13 rivoplus volumio[3183]: info: Saving local item Female Jul 18 09:29:13 rivoplus volumio[3183]: info: Pushing Favourites {"uri":"mnt/INTERNAL/Vocal/ARC 3 Demo & Evaluaton/03 Angel 1.aif","favourite":false} Jul 18 09:29:13 rivoplus volumio[3183]: info: Listing playlists Jul 18 09:29:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:29:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:29:24 rivoplus volumio[3183]: info: Discovery: Getting this device information Jul 18 09:29:24 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:24 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:29:29 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:29 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:29 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:29:29 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:29:47 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:29:47 rivoplus volumio[3183]: info: CURURI: music-library Jul 18 09:29:47 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:48 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:29:48 rivoplus volumio[3183]: info: CURURI: music-library/INTERNAL Jul 18 09:29:48 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:49 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:29:49 rivoplus volumio[3183]: info: CURURI: music-library/INTERNAL/Vocal Jul 18 09:29:49 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:50 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:29:50 rivoplus volumio[3183]: info: CURURI: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2 Jul 18 09:29:50 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/01 La miñona de Cataluña.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/02 Variations on a Theme of Mozart.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/03 Grand Solo in D.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/04 Suite Espanola.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/05 Suite Espanola.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/06 Capricho árabe.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/07 Lagrima.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/08 Maria.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/09 Mazurka in G.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/10 Recredos De La Alhambra.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/11 Recuerdos de viaje.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/12 Recuerdos de viaje.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/13 Recuerdos de viaje.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/14 Recuerdos de viaje.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/15 Serenata Espanola.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Preloading song: music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/16 Canco del Lladre.wav Jul 18 09:29:50 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/01 La miñona de Cataluña.wav in service mpd Jul 18 09:29:50 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F01%20La%20mi%C3%B1ona%20de%20Catalu%C3%B1a.wav&metadata=false Jul 18 09:29:50 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/01 La miñona de Cataluña.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/02 Variations on a Theme of Mozart.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F02%20Variations%20on%20a%20Theme%20of%20Mozart.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/02 Variations on a Theme of Mozart.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/03 Grand Solo in D.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F03%20Grand%20Solo%20in%20D.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/03 Grand Solo in D.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/04 Suite Espanola.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F04%20Suite%20Espanola.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/04 Suite Espanola.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/05 Suite Espanola.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F05%20Suite%20Espanola.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/05 Suite Espanola.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/06 Capricho árabe.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F06%20Capricho%20%C3%A1rabe.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/06 Capricho árabe.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/07 Lagrima.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F07%20Lagrima.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/07 Lagrima.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/08 Maria.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F08%20Maria.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/08 Maria.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/09 Mazurka in G.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F09%20Mazurka%20in%20G.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/09 Mazurka in G.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/10 Recredos De La Alhambra.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F10%20Recredos%20De%20La%20Alhambra.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/10 Recredos De La Alhambra.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/11 Recuerdos de viaje.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F11%20Recuerdos%20de%20viaje.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/11 Recuerdos de viaje.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/12 Recuerdos de viaje.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F12%20Recuerdos%20de%20viaje.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/12 Recuerdos de viaje.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/13 Recuerdos de viaje.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F13%20Recuerdos%20de%20viaje.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/13 Recuerdos de viaje.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/14 Recuerdos de viaje.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F14%20Recuerdos%20de%20viaje.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/14 Recuerdos de viaje.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/15 Serenata Espanola.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F15%20Serenata%20Espanola.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/15 Serenata Espanola.wav Jul 18 09:29:51 rivoplus volumio[3183]: info: Exploding uri music-library/INTERNAL/Vocal/100 Best Guitar Classics 2/16 Canco del Lladre.wav in service mpd Jul 18 09:29:51 rivoplus volumio[3183]: info: ALBUMART /albumart?cacheid=858&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FVocal%2F100%20Best%20Guitar%20Classics%202%2F16%20Canco%20del%20Lladre.wav&metadata=false Jul 18 09:29:51 rivoplus volumio[3183]: info: URI /mnt/INTERNAL/Vocal/100 Best Guitar Classics 2/16 Canco del Lladre.wav Jul 18 09:29:54 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:29:54 rivoplus volumio[3183]: info: CURURI: music-library/INTERNAL/Vocal/1.Demo Various Jul 18 09:29:54 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:29:54 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:29:54 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:29:54 rivoplus volumio[3183]: info: Discovery: Getting this device information Jul 18 09:29:54 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:29:54 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:29:54 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:30:10 rivoplus volumio[3183]: Searching plugin music_service/mpd Jul 18 09:30:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 18 09:30:10 rivoplus volumio[3183]: info: All search sources collected, pushing search results Jul 18 09:30:11 rivoplus volumio[3183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 18 09:30:11 rivoplus volumio[3183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 18 09:30:17 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::ClearQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::stPlaybackTimer Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::serviceStop Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::serviceStop Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::stop Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::clearPlayQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::addQueueItems Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::addQueueItems Jul 18 09:30:17 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:30:17 rivoplus volumio[3183]: info: Adding Item to queue: albums://ABC/Western%20Electric%20Test%20Record%208%20Live Jul 18 09:30:17 rivoplus volumio[3183]: info: Exploding uri albums://ABC/Western%20Electric%20Test%20Record%208%20Live in service mpd Jul 18 09:30:17 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPlay Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::play index 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::play index undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::startPlaybackTimer Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand stop took 59 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand stop took 11 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:30:17 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand status took 5 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand clear took 4 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac" Jul 18 09:30:17 rivoplus volumio[3183]: error: updateQueue error: null Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:30:17 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 8ms Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand add "INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac" took 5 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand play Jul 18 09:30:17 rivoplus volumio[3183]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 17ms Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 7ms Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand play took 4 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand status took 13 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand status took 4 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Beautiful","artist":"ABC","album":"Western Electric Test Record 8 Live","uri":"INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac","trackType":"flac"} Jul 18 09:30:17 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus stop Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 37ms Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Beautiful","artist":"ABC","album":"Western Electric Test Record 8 Live","uri":"INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac","trackType":"flac"} Jul 18 09:30:17 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:30:17 rivoplus volumio[3183]: info: Received an update from plugin. extracting info from payload Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 73ms Jul 18 09:30:17 rivoplus volumio[3183]: info: MCU Signalled Playback Inactive Jul 18 09:30:17 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:30:17 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:30:17 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:30:17 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:30:17 rivoplus volumio[3183]: info: MCU Signalled Playback Active Jul 18 09:30:19 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:21 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:21 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:24 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: Saving local item Female Jul 18 09:30:24 rivoplus volumio[3183]: info: Pushing Favourites {"uri":"/mnt/INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac","favourite":false} Jul 18 09:30:24 rivoplus volumio[3183]: info: Listing playlists Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:30:24 rivoplus volumio[3183]: info: Discovery: Getting this device information Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:24 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 18 09:30:30 rivoplus ntpd[18307]: ntpd exiting on signal 15 (Terminated) Jul 18 09:30:30 rivoplus systemd[1]: Stopping Network Time Service... Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 18 09:30:30 rivoplus ntpd[18307]: 103.83.142.30 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 103.134.77.146 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 103.169.192.230 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 119.110.74.101 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 202.162.32.12 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 203.114.225.252 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 202.65.114.202 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 160.30.225.225 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 18 09:30:30 rivoplus systemd[1]: ntp.service: Succeeded. Jul 18 09:30:30 rivoplus systemd[1]: Stopped Network Time Service. Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 18 09:30:30 rivoplus systemd[1]: Starting Network Time Service... Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 18 09:30:31 rivoplus ntpd[19717]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 18 09:30:31 rivoplus ntpd[19717]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 18 09:30:31 rivoplus systemd[1]: Started Network Time Service. Jul 18 09:30:31 rivoplus ntpd[19733]: proto: precision = 1.208 usec (-20) Jul 18 09:30:31 rivoplus ntpd[19733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 18 09:30:31 rivoplus ntpd[19733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 18 09:30:31 rivoplus ntpd[19733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 934 days ago Jul 18 09:30:31 rivoplus ntpd[19733]: Listen and drop on 0 v6wildcard [::]:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen normally on 2 lo 127.0.0.1:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen normally on 3 eth0 192.168.88.106:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen normally on 4 wlan0 169.254.122.147:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listening on routing socket on fd #21 for interface updates Jul 18 09:30:31 rivoplus ntpd[19733]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 18 09:30:31 rivoplus ntpd[19733]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 18 09:30:32 rivoplus ntpd[19733]: Soliciting pool server 202.162.32.12 Jul 18 09:30:33 rivoplus ntpd[19733]: Soliciting pool server 103.83.142.30 Jul 18 09:30:33 rivoplus ntpd[19733]: Soliciting pool server 202.65.114.202 Jul 18 09:30:34 rivoplus ntpd[19733]: Soliciting pool server 160.30.224.224 Jul 18 09:30:34 rivoplus ntpd[19733]: Soliciting pool server 103.105.57.11 Jul 18 09:30:34 rivoplus ntpd[19733]: Soliciting pool server 203.114.224.252 Jul 18 09:30:34 rivoplus volumio[3183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:30:34 rivoplus volumio[3183]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Jul 18 09:30:34 rivoplus volumio[3183]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Jul 18 09:30:34 rivoplus volumio[3183]: errno: -3007, Jul 18 09:30:34 rivoplus volumio[3183]: code: 'ENOTFOUND', Jul 18 09:30:34 rivoplus volumio[3183]: syscall: 'getaddrinfo', Jul 18 09:30:34 rivoplus volumio[3183]: hostname: 'lastfm.freetls.fastly.net' Jul 18 09:30:34 rivoplus volumio[3183]: } Jul 18 09:30:34 rivoplus volumio[3183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 203.114.74.17 Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 27.54.117.72 Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 103.105.56.11 Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 203.89.31.13 Jul 18 09:30:35 rivoplus sudo[19810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 09:29 Jul 18 09:30:35 rivoplus sudo[19810]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:55:44 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="66719bc94798fca30ca68a8e6d955a83"