-- Logs begin at Fri 2024-08-09 00:57:02 JST, end at Mon 2024-08-26 23:30:57 JST. -- Aug 26 23:29:00 rivo volumio[3273]: info: Executing endpoint metavolumio Aug 26 23:29:00 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 23:29:01 rivo volumio[3273]: info: Preload queue cleared Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::ClearQueue Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::stop Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::clearPlayQueue Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::saveQueue Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushQueue Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::addQueueItems Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::addQueueItems Aug 26 23:29:01 rivo volumio[3273]: info: Preload queue cleared Aug 26 23:29:01 rivo volumio[3273]: info: Adding Item to queue: artists://Kate%20Miller-Heidke/The%20Best%20of%20Kate%20Miller-Heidke%3A%20Act%20One Aug 26 23:29:01 rivo volumio[3273]: info: Exploding uri artists://Kate%20Miller-Heidke/The%20Best%20of%20Kate%20Miller-Heidke%3A%20Act%20One in service mpd Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushQueue Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::saveQueue Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::updateTrackBlock Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrackBlock Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPlay Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::play index 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::stop Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::play index undefined Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::startPlaybackTimer Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::clearAddPlayTracks USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3 Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand stop took 4 milliseconds Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand clear took 1 milliseconds Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand add "USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3" Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces system playlist update Aug 26 23:29:01 rivo volumio[3273]: info: Ignoring MPD Status Update Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces system playlist update Aug 26 23:29:01 rivo volumio[3273]: info: Ignoring MPD Status Update Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand add "USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3" took 2 milliseconds Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand play Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces system playlist update Aug 26 23:29:01 rivo volumio[3273]: info: Ignoring MPD Status Update Aug 26 23:29:01 rivo kernel: aml_spdif_open Aug 26 23:29:01 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Aug 26 23:29:01 rivo kernel: set normal 512 fs /4 fs Aug 26 23:29:01 rivo kernel: set spdifout clk:5644800, mpll:22579200 Aug 26 23:29:01 rivo kernel: get spdifout clk:5644797, mpll:22579186 Aug 26 23:29:01 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Aug 26 23:29:01 rivo kernel: set normal 512 fs /4 fs Aug 26 23:29:01 rivo kernel: set spdifout clk:5644800, mpll:22579200 Aug 26 23:29:01 rivo kernel: get spdifout clk:5644797, mpll:22579186 Aug 26 23:29:01 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 26 23:29:01 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Aug 26 23:29:01 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 26 23:29:01 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces system playlist update Aug 26 23:29:01 rivo volumio[3273]: info: Ignoring MPD Status Update Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces state update: player Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 12ms Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 11ms Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand play took 10 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::getState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand status Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 10ms Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 6ms Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces system playlist update Aug 26 23:29:01 rivo volumio[3273]: info: Ignoring MPD Status Update Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces state update: player Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::getState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand status Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces system playlist update Aug 26 23:29:01 rivo volumio[3273]: info: Ignoring MPD Status Update Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces state update: player Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::getState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand status Aug 26 23:29:01 rivo volumio[3273]: error: Upnp client error: Error: This socket has been ended by the other party Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces state update: player Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::getState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand status Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand status took 26 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 24ms Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand status took 23 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 22ms Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand status took 16 milliseconds Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces state update: player Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::getState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand status Aug 26 23:29:01 rivo volumio[3273]: info: Aug 26 23:29:01 rivo volumio[3273]: ---------------------------- MPD announces state update: player Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::getState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand status Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand status took 13 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand status took 7 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand status took 6 milliseconds Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseTrackInfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseTrackInfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseTrackInfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseState Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Australian Idol","artist":"Kate Miller-Heidke","album":"The Best of Kate Miller-Heidke: Act One","uri":"USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3","trackType":"mp3"} Aug 26 23:29:01 rivo volumio[3273]: verbose: CURRENT POSITION 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus stop Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Australian Idol","artist":"Kate Miller-Heidke","album":"The Best of Kate Miller-Heidke: Act One","uri":"USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3","trackType":"mp3"} Aug 26 23:29:01 rivo volumio[3273]: verbose: CURRENT POSITION 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Aug 26 23:29:01 rivo volumio[3273]: info: Received an update from plugin. extracting info from payload Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Australian Idol","artist":"Kate Miller-Heidke","album":"The Best of Kate Miller-Heidke: Act One","uri":"USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3","trackType":"mp3"} Aug 26 23:29:01 rivo volumio[3273]: verbose: CURRENT POSITION 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Aug 26 23:29:01 rivo volumio[3273]: info: Received an update from plugin. extracting info from payload Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 88ms Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 109ms Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 103ms Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand playlistinfo took 75 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 26 23:29:01 rivo volumio[3273]: info: sendMpdCommand playlistinfo took 72 milliseconds Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseTrackInfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseTrackInfo Aug 26 23:29:01 rivo volumio[3273]: verbose: ControllerMpd::parseTrackInfo Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Australian Idol","artist":"Kate Miller-Heidke","album":"The Best of Kate Miller-Heidke: Act One","uri":"USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3","trackType":"mp3"} Aug 26 23:29:01 rivo volumio[3273]: verbose: CURRENT POSITION 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Aug 26 23:29:01 rivo volumio[3273]: info: Received an update from plugin. extracting info from payload Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Australian Idol","artist":"Kate Miller-Heidke","album":"The Best of Kate Miller-Heidke: Act One","uri":"USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3","trackType":"mp3"} Aug 26 23:29:01 rivo volumio[3273]: verbose: CURRENT POSITION 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Aug 26 23:29:01 rivo volumio[3273]: info: Received an update from plugin. extracting info from payload Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: ControllerMpd::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Australian Idol","artist":"Kate Miller-Heidke","album":"The Best of Kate Miller-Heidke: Act One","uri":"USB/SONY_32GU/Music/Kate Miller-Heidke/The Best of Kate Miller-Heidke Act One/02 Australian Idol.mp3","trackType":"mp3"} Aug 26 23:29:01 rivo volumio[3273]: verbose: CURRENT POSITION 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Aug 26 23:29:01 rivo volumio[3273]: info: Received an update from plugin. extracting info from payload Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: CoreStateMachine::pushState Aug 26 23:29:01 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:01 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Aug 26 23:29:01 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 217ms Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 210ms Aug 26 23:29:01 rivo volumio[3273]: info: ------------------------------ 209ms Aug 26 23:29:01 rivo volumio[3273]: info: Signalling Playback active due to playback status change Aug 26 23:29:01 rivo volumio[3273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kate%20Miller-Heidke/Nightflight/1b4eef07-f3e6-4961-bee9-bc0e0e868f43.png' Aug 26 23:29:01 rivo volumio[3273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kate%20Miller-Heidke/Nightflight/1b4eef07-f3e6-4961-bee9-bc0e0e868f43.png' Aug 26 23:29:01 rivo volumio[3273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kate%20Miller-Heidke/Little%20Eve/1aafb278-37c4-4353-ae9c-7e34d9203e0b.png' Aug 26 23:29:01 rivo volumio[3273]: info: MCU Signalled Playback Active Aug 26 23:29:02 rivo volumio[3273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kate%20Miller-Heidke/Nightflight/1b4eef07-f3e6-4961-bee9-bc0e0e868f43.png' Aug 26 23:29:02 rivo volumio[3273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kate%20Miller-Heidke/Nightflight/1b4eef07-f3e6-4961-bee9-bc0e0e868f43.png' Aug 26 23:29:03 rivo volumio[3273]: info: Executing endpoint metavolumio Aug 26 23:29:03 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 23:29:03 rivo volumio[3273]: info: Executing endpoint metavolumio Aug 26 23:29:03 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 23:29:03 rivo volumio[3273]: info: Executing endpoint metavolumio Aug 26 23:29:03 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 23:29:16 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 23:29:16 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 23:29:16 rivo volumio[3273]: info: Discovery: Getting this device information Aug 26 23:29:16 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Aug 26 23:29:16 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Aug 26 23:29:16 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 23:30:56 rivo volumio[3273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 23:30:56 rivo volumio[3273]: Error: connect ETIMEDOUT 146.75.114.79:80 Aug 26 23:30:56 rivo volumio[3273]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 26 23:30:56 rivo volumio[3273]: errno: -110, Aug 26 23:30:56 rivo volumio[3273]: code: 'ETIMEDOUT', Aug 26 23:30:56 rivo volumio[3273]: syscall: 'connect', Aug 26 23:30:56 rivo volumio[3273]: address: '146.75.114.79', Aug 26 23:30:56 rivo volumio[3273]: port: 80 Aug 26 23:30:56 rivo volumio[3273]: } Aug 26 23:30:56 rivo volumio[3273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 23:30:57 rivo sudo[11132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 23:29 Aug 26 23:30:57 rivo sudo[11132]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:06:46 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="420eec03f218d056e8306370042003fc"