-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2025-09-14 17:45:51 CST. -- Sep 14 17:44:12 volumio ntpd[859]: Soliciting pool server 210.28.130.4 Sep 14 17:44:16 volumio ntpd[859]: Soliciting pool server 84.16.73.33 Sep 14 17:44:19 volumio ntpd[859]: Soliciting pool server 162.159.200.1 Sep 14 17:44:20 volumio ntpd[859]: Soliciting pool server 185.209.85.222 Sep 14 17:44:21 volumio ntpd[859]: Soliciting pool server 84.16.67.12 Sep 14 17:44:30 volumio ntpd[859]: Soliciting pool server 193.182.111.14 Sep 14 17:44:31 volumio volumio[940]: verbose: New Socket.io Connection to 192.168.0.108 from 192.168.0.102 UA: Mozilla/5.0 (Linux; Android 15; FRI-AN10 Build/HONORFRI-AN10;) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/97.0.4692.98 Mobile Safari/537.36 T7/13.38 SP-engine/2.76.0 languageType/0 bdh_dvt/6 bdh_de/1 bdh_ds/1 bdapp/1.0 (bdhonorbrowser; bdhonorbrowser) bdhonorbrowser/9.4.0.5 (P1 15) NABar/1.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Sep 14 17:44:31 volumio volumio[940]: info: CorePlayQueue::getTrack 2 Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::volumioGetQueue Sep 14 17:44:31 volumio volumio[940]: info: CoreStateMachine::getQueue Sep 14 17:44:31 volumio volumio[940]: info: CorePlayQueue::getQueue Sep 14 17:44:31 volumio volumio[940]: info: Listing playlists Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 17:44:31 volumio volumio[940]: info: Received Get System Info Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 17:44:31 volumio volumio[940]: info: Discovery: Getting this device information Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Sep 14 17:44:31 volumio volumio[940]: info: CorePlayQueue::getTrack 2 Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Sep 14 17:44:31 volumio volumio[940]: info: CorePlayQueue::getTrack 2 Sep 14 17:44:31 volumio ntpd[859]: Soliciting pool server 5.79.108.34 Sep 14 17:44:31 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 14 17:44:58 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:44:58 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN Sep 14 17:44:58 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:00 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:00 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001 Sep 14 17:45:00 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:04 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:04 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J Sep 14 17:45:04 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:04 volumio volumio[940]: info: Preloading song: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf Sep 14 17:45:04 volumio volumio[940]: info: Preloading song: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Summer L' Eatate.dsf Sep 14 17:45:04 volumio volumio[940]: info: Preloading song: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Autumn L' Autunno.dsf Sep 14 17:45:04 volumio volumio[940]: info: Preloading song: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Winter L' Inverno.dsf Sep 14 17:45:04 volumio volumio[940]: info: Exploding uri music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf in service mpd Sep 14 17:45:04 volumio volumio[940]: info: ALBUMART /albumart?cacheid=128&web=UNAMAS%20String%20Sextet/ViVa%20The%20Four%20Seasons/extralarge&path=%2Fmnt%2FUSB%2FA1C30542C0D84AE1%2F%24RECYCLE.BIN%2FS-1-5-21-3543358040-3165850322-3552360631-1001%2F%24R31DZ7J%2FSpring%20La%20Primavera.dsf&metadata=false Sep 14 17:45:04 volumio volumio[940]: info: URI /mnt/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf Sep 14 17:45:04 volumio volumio[940]: info: Exploding uri music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Summer L' Eatate.dsf in service mpd Sep 14 17:45:04 volumio volumio[940]: info: ALBUMART /albumart?cacheid=128&web=UNAMAS%20String%20Sextet/ViVa%20The%20Four%20Seasons/extralarge&path=%2Fmnt%2FUSB%2FA1C30542C0D84AE1%2F%24RECYCLE.BIN%2FS-1-5-21-3543358040-3165850322-3552360631-1001%2F%24R31DZ7J%2FSummer%20L'%20Eatate.dsf&metadata=false Sep 14 17:45:04 volumio volumio[940]: info: URI /mnt/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Summer L' Eatate.dsf Sep 14 17:45:04 volumio volumio[940]: info: Exploding uri music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Autumn L' Autunno.dsf in service mpd Sep 14 17:45:04 volumio volumio[940]: info: ALBUMART /albumart?cacheid=128&web=UNAMAS%20String%20Sextet/ViVa%20The%20Four%20Seasons/extralarge&path=%2Fmnt%2FUSB%2FA1C30542C0D84AE1%2F%24RECYCLE.BIN%2FS-1-5-21-3543358040-3165850322-3552360631-1001%2F%24R31DZ7J%2FAutumn%20L'%20Autunno.dsf&metadata=false Sep 14 17:45:04 volumio volumio[940]: info: URI /mnt/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Autumn L' Autunno.dsf Sep 14 17:45:04 volumio volumio[940]: info: Exploding uri music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Winter L' Inverno.dsf in service mpd Sep 14 17:45:04 volumio volumio[940]: info: ALBUMART /albumart?cacheid=128&web=UNAMAS%20String%20Sextet/ViVa%20The%20Four%20Seasons/extralarge&path=%2Fmnt%2FUSB%2FA1C30542C0D84AE1%2F%24RECYCLE.BIN%2FS-1-5-21-3543358040-3165850322-3552360631-1001%2F%24R31DZ7J%2FWinter%20L'%20Inverno.dsf&metadata=false Sep 14 17:45:04 volumio volumio[940]: info: URI /mnt/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Winter L' Inverno.dsf Sep 14 17:45:05 volumio volumio[940]: info: CorePlayQueue::getTrack 2 Sep 14 17:45:05 volumio volumio[940]: info: CorePlayQueue::getTrack 3 Sep 14 17:45:09 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:09 volumio volumio[940]: info: CURURI: music-library Sep 14 17:45:09 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:10 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:10 volumio volumio[940]: info: CURURI: music-library/USB Sep 14 17:45:10 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:11 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1 Sep 14 17:45:11 volumio volumio[940]: info: Sep 14 17:45:11 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:11 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:11 volumio volumio[940]: info: Sep 14 17:45:11 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:11 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:11 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:11 volumio volumio[940]: info: Sep 14 17:45:11 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:11 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:11 volumio volumio[940]: info: Sep 14 17:45:11 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:11 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:11 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:11 volumio volumio[940]: info: Sep 14 17:45:11 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:11 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:11 volumio volumio[940]: info: Sep 14 17:45:11 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:11 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:11 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:11 volumio volumio[940]: error: updateQueue error: null Sep 14 17:45:11 volumio volumio[940]: error: updateQueue error: null Sep 14 17:45:11 volumio volumio[940]: info: ------------------------------ 8ms Sep 14 17:45:11 volumio volumio[940]: info: sendMpdCommand status took 7 milliseconds Sep 14 17:45:11 volumio volumio[940]: info: ------------------------------ 5ms Sep 14 17:45:11 volumio volumio[940]: info: sendMpdCommand status took 5 milliseconds Sep 14 17:45:11 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:11 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 2 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 2 Sep 14 17:45:11 volumio volumio[940]: 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} Sep 14 17:45:11 volumio volumio[940]: verbose: CURRENT POSITION 2 Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::syncState stateService stop Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::stPlaybackTimer Sep 14 17:45:11 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: 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} Sep 14 17:45:11 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::syncState stateService stop Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus stop Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: No code Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: ------------------------------ 30ms Sep 14 17:45:11 volumio volumio[940]: info: ------------------------------ 28ms Sep 14 17:45:11 volumio volumio[940]: error: updateQueue error: null Sep 14 17:45:11 volumio volumio[940]: info: ------------------------------ 28ms Sep 14 17:45:11 volumio volumio[940]: info: sendMpdCommand status took 26 milliseconds Sep 14 17:45:11 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:11 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: 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} Sep 14 17:45:11 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::syncState stateService stop Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus stop Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: No code Sep 14 17:45:11 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:11 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:11 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:11 volumio volumio[940]: info: ------------------------------ 44ms Sep 14 17:45:11 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:11 volumio volumio[940]: info: Preloading song: music-library/USB/A1C30542C0D84AE1/WAV241MB.wav Sep 14 17:45:11 volumio volumio[940]: info: Preloading song: music-library/USB/A1C30542C0D84AE1/02 - 棋子.flac Sep 14 17:45:11 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Flight/Assassin%27s%20Creed%20Odyssey%20(Original%20Game%20Soundtrack)/cc16af72-ada1-44f3-8507-1eb8062d73fd.jpg' Sep 14 17:45:11 volumio volumio[940]: info: Exploding uri music-library/USB/A1C30542C0D84AE1/WAV241MB.wav in service mpd Sep 14 17:45:11 volumio volumio[940]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FA1C30542C0D84AE1%2FWAV241MB.wav&metadata=false Sep 14 17:45:11 volumio volumio[940]: info: URI /mnt/USB/A1C30542C0D84AE1/WAV241MB.wav Sep 14 17:45:11 volumio volumio[940]: info: Exploding uri music-library/USB/A1C30542C0D84AE1/02 - 棋子.flac in service mpd Sep 14 17:45:11 volumio volumio[940]: info: ALBUMART /albumart?cacheid=128&web=Faye%20Wong/%E5%A4%A9%E7%A9%BA/extralarge&path=%2Fmnt%2FUSB%2FA1C30542C0D84AE1%2F02%20-%20%E6%A3%8B%E5%AD%90.flac&metadata=false Sep 14 17:45:11 volumio volumio[940]: info: URI /mnt/USB/A1C30542C0D84AE1/02 - 棋子.flac Sep 14 17:45:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:13 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN Sep 14 17:45:13 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:14 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:14 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001 Sep 14 17:45:14 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 14 17:45:15 volumio volumio[940]: info: CURURI: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J Sep 14 17:45:15 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:17 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::ClearQueue Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::stop Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::clearPlayQueue Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::saveQueue Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushQueue Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::addQueueItems Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::addQueueItems Sep 14 17:45:17 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:17 volumio volumio[940]: info: Adding Item to queue: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf Sep 14 17:45:17 volumio volumio[940]: info: Using cached record of: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushQueue Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::saveQueue Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::updateTrackBlock Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrackBlock Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPlay Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::play index 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::addQueueItems Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::addQueueItems Sep 14 17:45:17 volumio volumio[940]: info: Preload queue cleared Sep 14 17:45:17 volumio volumio[940]: info: Adding Item to queue: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Summer L' Eatate.dsf Sep 14 17:45:17 volumio volumio[940]: info: Using cached record of: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Summer L' Eatate.dsf Sep 14 17:45:17 volumio volumio[940]: info: Adding Item to queue: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Autumn L' Autunno.dsf Sep 14 17:45:17 volumio volumio[940]: info: Using cached record of: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Autumn L' Autunno.dsf Sep 14 17:45:17 volumio volumio[940]: info: Adding Item to queue: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Winter L' Inverno.dsf Sep 14 17:45:17 volumio volumio[940]: info: Using cached record of: music-library/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Winter L' Inverno.dsf Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::stop Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushQueue Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::saveQueue Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::play index undefined Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::updateTrackBlock Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrackBlock Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::startPlaybackTimer Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::clearAddPlayTracks USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand stop took 2 milliseconds Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:17 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand clear took 1 milliseconds Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand add "USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf" Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:17 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:17 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:17 volumio volumio[940]: error: updateQueue error: null Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:17 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 4ms Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand add "USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf" took 3 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 3ms Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 2ms Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand play Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:17 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces system playlist update Sep 14 17:45:17 volumio volumio[940]: info: Ignoring MPD Status Update Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 4ms Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand play took 3 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 3ms Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 2ms Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:17 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand status took 14 milliseconds Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand status took 16 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand status took 15 milliseconds Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:17 volumio volumio[940]: info: Sep 14 17:45:17 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand status took 5 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand status took 3 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand status took 2 milliseconds Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:17 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus stop Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:17 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:17 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:17 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:17 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 35ms Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 44ms Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 42ms Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 14 17:45:17 volumio volumio[940]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 14 17:45:17 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:17 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:17 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:17 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:17 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:17 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:17 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:17 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:17 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 61ms Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 58ms Sep 14 17:45:17 volumio volumio[940]: info: ------------------------------ 58ms Sep 14 17:45:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 17:45:25 volumio kernel: sched: RT throttling activated Sep 14 17:45:29 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioSeek Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::seek Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: TRACKBLOCK {"uri":"mnt/USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","service":"mpd","name":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=128&web=UNAMAS%20String%20Sextet/ViVa%20The%20Four%20Seasons/extralarge&path=%2FUSB%2FA1C30542C0D84AE1%2F%24RECYCLE.BIN%2FS-1-5-21-3543358040-3165850322-3552360631-1001%2F%24R31DZ7J&metadata=false","duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::startPlaybackTimer Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::seek Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: error: null Sep 14 17:45:45 volumio volumio[940]: info: Sep 14 17:45:45 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:45 volumio volumio[940]: info: Sep 14 17:45:45 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:45 volumio volumio[940]: info: Sep 14 17:45:45 volumio volumio[940]: ---------------------------- MPD announces state update: player Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::getState Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Sep 14 17:45:45 volumio volumio[940]: info: sendMpdCommand status took 3 milliseconds Sep 14 17:45:45 volumio volumio[940]: info: sendMpdCommand status took 3 milliseconds Sep 14 17:45:45 volumio volumio[940]: info: sendMpdCommand status took 2 milliseconds Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::parseState Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 17:45:45 volumio volumio[940]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 17:45:45 volumio volumio[940]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 17:45:45 volumio volumio[940]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:45 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":144000,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:45 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:45 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":144000,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:45 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:45 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: info: ControllerMpd::pushState Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::servicePushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":144000,"duration":688,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Spring La Primavera","artist":"UNAMAS String Sextet","album":"ViVa The Four Seasons","uri":"USB/A1C30542C0D84AE1/$RECYCLE.BIN/S-1-5-21-3543358040-3165850322-3552360631-1001/$R31DZ7J/Spring La Primavera.dsf","trackType":"dsf"} Sep 14 17:45:45 volumio volumio[940]: verbose: CURRENT POSITION 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Sep 14 17:45:45 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: info: CoreStateMachine::pushState Sep 14 17:45:45 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Sep 14 17:45:45 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Sep 14 17:45:45 volumio volumio[940]: info: ------------------------------ 41ms Sep 14 17:45:45 volumio volumio[940]: info: ------------------------------ 40ms Sep 14 17:45:45 volumio volumio[940]: info: ------------------------------ 40ms Sep 14 17:45:50 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 17:45:50 volumio volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 17:45:50 volumio volumio[940]: Error: connect ETIMEDOUT 199.59.149.205:443 Sep 14 17:45:50 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 14 17:45:50 volumio volumio[940]: errno: -110, Sep 14 17:45:50 volumio volumio[940]: code: 'ETIMEDOUT', Sep 14 17:45:50 volumio volumio[940]: syscall: 'connect', Sep 14 17:45:50 volumio volumio[940]: address: '199.59.149.205', Sep 14 17:45:50 volumio volumio[940]: port: 443 Sep 14 17:45:50 volumio volumio[940]: } Sep 14 17:45:50 volumio volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 17:45:51 volumio sudo[5921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 17:44 Sep 14 17:45:51 volumio sudo[5921]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"