-- Logs begin at Sat 2026-08-29 15:04:27 CST, end at Sat 2026-08-29 15:13:38 CST. -- Aug 29 15:12:00 volumio volumio[901]: info: Preload queue cleared Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::ClearQueue Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::stop Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::serviceStop Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::serviceStop Aug 29 15:12:00 volumio volumio[901]: info: ControllerMpd::stop Aug 29 15:12:00 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::clearPlayQueue Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 29 15:12:00 volumio volumio[901]: info: Preload queue cleared Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002tzVBs3pGe6R.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002tzVBs3pGe6R.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::play index 1 Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 29 15:12:00 volumio volumio[901]: info: Preload queue cleared Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000029iLeM0f1Z8D.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000029iLeM0f1Z8D.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Letter from Rosie(罗西的来信) - RVAN.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Letter from Rosie(罗西的来信) - RVAN.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Everything I Do - Brandy.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Everything I Do - Brandy.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003AsCIk3AcCKT.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003AsCIk3AcCKT.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002mYF8f1QDlRJ.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002mYF8f1QDlRJ.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002YwZf103yics_1.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002YwZf103yics_1.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000KVeyi2TBhs6.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000KVeyi2TBhs6.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000lFwoH1ET1OS.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000lFwoH1ET1OS.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003uPmE31vozLt.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003uPmE31vozLt.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003z4jdG31pjpr.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003z4jdG31pjpr.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003yWnv14YFVKP_1.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003yWnv14YFVKP_1.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/A Thousand Kisses Deep - Leonard Cohen.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/A Thousand Kisses Deep - Leonard Cohen.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002fNUO23oxbQy.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002fNUO23oxbQy.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002jfs8G3D0Za0.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002jfs8G3D0Za0.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003b7YKB1NwxdJ.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003b7YKB1NwxdJ.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002zbsxw0c9LqY.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002zbsxw0c9LqY.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002oDhWY36vE8u.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002oDhWY36vE8u.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Feeling Good - Michael Bublé.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Feeling Good - Michael Bublé.flac Aug 29 15:12:00 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001ycVcs0ISuZ5.flac Aug 29 15:12:00 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001ycVcs0ISuZ5.flac Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::stop Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::play index undefined Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:00 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:12:00 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:00 volumio volumio[901]: verbose: ControllerMpd::clearAddPlayTracks USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:12:00 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:12:00 volumio volumio[901]: info: Aug 29 15:12:00 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:00 volumio volumio[901]: info: sendMpdCommand stop took 70 milliseconds Aug 29 15:12:00 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:00 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:00 volumio volumio[901]: info: sendMpdCommand stop took 23 milliseconds Aug 29 15:12:00 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 15:12:01 volumio volumio[901]: info: Aug 29 15:12:01 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 29 15:12:01 volumio volumio[901]: info: Ignoring MPD Status Update Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand status took 15 milliseconds Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand clear took 15 milliseconds Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac" Aug 29 15:12:01 volumio volumio[901]: error: updateQueue error: null Aug 29 15:12:01 volumio volumio[901]: info: Aug 29 15:12:01 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 29 15:12:01 volumio volumio[901]: info: Ignoring MPD Status Update Aug 29 15:12:01 volumio volumio[901]: info: ------------------------------ 6ms Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac" took 3 milliseconds Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:12:01 volumio volumio[901]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 15:12:01 volumio volumio[901]: info: ------------------------------ 26ms Aug 29 15:12:01 volumio volumio[901]: info: Aug 29 15:12:01 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:01 volumio volumio[901]: info: ------------------------------ 16ms Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand play took 13 milliseconds Aug 29 15:12:01 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:01 volumio volumio[901]: info: Aug 29 15:12:01 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand status took 3 milliseconds Aug 29 15:12:01 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand status took 4 milliseconds Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:01 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:12:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:12:01 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:01 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"185 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:12:01 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:12:01 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 29 15:12:01 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:12:01 volumio volumio[901]: info: ------------------------------ 20ms Aug 29 15:12:01 volumio volumio[901]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 29 15:12:01 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:01 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:12:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:12:01 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:01 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"543 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:12:01 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:12:01 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 29 15:12:01 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:12:01 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 29 15:12:01 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:01 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:12:01 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:01 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:01 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:01 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:01 volumio volumio[901]: info: ------------------------------ 55ms Aug 29 15:12:02 volumio volumio[901]: info: Preload queue cleared Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::ClearQueue Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::stop Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::serviceStop Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::serviceStop Aug 29 15:12:02 volumio volumio[901]: info: ControllerMpd::stop Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::clearPlayQueue Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 29 15:12:02 volumio volumio[901]: info: Preload queue cleared Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002tzVBs3pGe6R.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002tzVBs3pGe6R.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::play index 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 29 15:12:02 volumio volumio[901]: info: Preload queue cleared Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000029iLeM0f1Z8D.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000029iLeM0f1Z8D.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Letter from Rosie(罗西的来信) - RVAN.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Letter from Rosie(罗西的来信) - RVAN.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Everything I Do - Brandy.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Everything I Do - Brandy.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003AsCIk3AcCKT.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003AsCIk3AcCKT.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002mYF8f1QDlRJ.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002mYF8f1QDlRJ.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002YwZf103yics_1.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002YwZf103yics_1.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000KVeyi2TBhs6.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000KVeyi2TBhs6.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000lFwoH1ET1OS.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000lFwoH1ET1OS.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003uPmE31vozLt.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003uPmE31vozLt.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003z4jdG31pjpr.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003z4jdG31pjpr.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003yWnv14YFVKP_1.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003yWnv14YFVKP_1.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/A Thousand Kisses Deep - Leonard Cohen.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/A Thousand Kisses Deep - Leonard Cohen.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002fNUO23oxbQy.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002fNUO23oxbQy.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002jfs8G3D0Za0.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002jfs8G3D0Za0.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003b7YKB1NwxdJ.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003b7YKB1NwxdJ.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002zbsxw0c9LqY.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002zbsxw0c9LqY.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002oDhWY36vE8u.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002oDhWY36vE8u.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Feeling Good - Michael Bublé.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Feeling Good - Michael Bublé.flac Aug 29 15:12:02 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001ycVcs0ISuZ5.flac Aug 29 15:12:02 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001ycVcs0ISuZ5.flac Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::stop Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::play index undefined Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::clearAddPlayTracks USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:12:02 volumio volumio[901]: info: Aug 29 15:12:02 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand stop took 65 milliseconds Aug 29 15:12:02 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand stop took 10 milliseconds Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 15:12:02 volumio volumio[901]: info: Aug 29 15:12:02 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 29 15:12:02 volumio volumio[901]: info: Ignoring MPD Status Update Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand status took 3 milliseconds Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand clear took 2 milliseconds Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac" Aug 29 15:12:02 volumio volumio[901]: error: updateQueue error: null Aug 29 15:12:02 volumio volumio[901]: info: Aug 29 15:12:02 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 29 15:12:02 volumio volumio[901]: info: Ignoring MPD Status Update Aug 29 15:12:02 volumio volumio[901]: info: ------------------------------ 5ms Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac" took 3 milliseconds Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:12:02 volumio volumio[901]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 15:12:02 volumio volumio[901]: info: ------------------------------ 10ms Aug 29 15:12:02 volumio volumio[901]: info: ------------------------------ 5ms Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand play took 3 milliseconds Aug 29 15:12:02 volumio volumio[901]: info: Aug 29 15:12:02 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:02 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:02 volumio volumio[901]: info: Aug 29 15:12:02 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand status took 7 milliseconds Aug 29 15:12:02 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand status took 4 milliseconds Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:02 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"185 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:12:02 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:12:02 volumio volumio[901]: info: ------------------------------ 19ms Aug 29 15:12:02 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 15:12:02 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:02 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:12:02 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:12:02 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:02 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:02 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:02 volumio volumio[901]: info: ------------------------------ 50ms Aug 29 15:12:03 volumio volumio[901]: info: CoreCommandRouter::volumioPause Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::pause Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::servicePause Aug 29 15:12:03 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:03 volumio volumio[901]: info: CoreCommandRouter::servicePause Aug 29 15:12:03 volumio volumio[901]: info: ControllerMpd::pause Aug 29 15:12:03 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 15:12:03 volumio volumio[901]: info: sendMpdCommand pause took 2 milliseconds Aug 29 15:12:03 volumio volumio[901]: info: Aug 29 15:12:03 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:12:03 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:12:03 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:12:03 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Aug 29 15:12:03 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:12:03 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:12:03 volumio volumio[901]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 29 15:12:03 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:12:03 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:12:03 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:12:03 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:03 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2094,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"560 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:12:03 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:03 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:12:03 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:03 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:12:03 volumio volumio[901]: info: ------------------------------ 37ms Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 15:12:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 15:12:43 volumio volumio[901]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Aug 29 15:12:43 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:43 volumio volumio[901]: info: Restoring Previous Volume level: 100 false false Aug 29 15:12:43 volumio volumio[901]: info: Output device has changed, restarting MPD Aug 29 15:12:43 volumio sudo[2607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 15:12:43 volumio sudo[2607]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:12:43 volumio sudo[2607]: pam_unix(sudo:session): session closed for user root Aug 29 15:12:43 volumio volumio[901]: info: Output device has changed, restarting Shairport Sync Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:12:43 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 15:12:43 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:12:43 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 15:12:43 volumio volumio[901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 15:12:43 volumio volumio[901]: info: Volume configurations have been set Aug 29 15:12:43 volumio systemd[1]: mpd.service: Succeeded. Aug 29 15:12:43 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 15:12:43 volumio systemd[1]: Starting Music Player Daemon... Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 15:12:43 volumio volumio[901]: info: Updating Volume Controller Parameters: Device: 5 Name: YULONG Audio DSD DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 15:12:43 volumio volumio[901]: info: Disabling external Volume Control Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:12:43 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:43 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:43 volumio volumio[901]: info: MPD Permissions set Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:12:43 volumio sudo[2615]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 15:12:43 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:12:43 volumio sudo[2615]: pam_unix(sudo:session): session closed for user root Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 15:12:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 15:12:43 volumio volumio[901]: info: Starting Shairport Sync Aug 29 15:12:43 volumio sudo[2631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 15:12:43 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:12:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 15:12:43 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 15:12:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 15:12:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 15:12:43 volumio sudo[2631]: pam_unix(sudo:session): session closed for user root Aug 29 15:12:43 volumio volumio[901]: info: Shairport-Sync Started Aug 29 15:12:44 volumio mpd[2620]: Aug 29 15:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 15:12:44 volumio systemd[1]: Started Music Player Daemon. Aug 29 15:12:44 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root Aug 29 15:12:44 volumio volumio[901]: error: updateQueue error: null Aug 29 15:12:47 volumio volumio[901]: info: VolumeController::SetAlsaVolume100 Aug 29 15:12:47 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:12:47 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:47 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:12:51 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Aug 29 15:12:51 volumio volumio[901]: info: CoreStateMachine::play index undefined Aug 29 15:12:51 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:51 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:51 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:12:51 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:51 volumio volumio[901]: info: ControllerMpd::resume Aug 29 15:12:51 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:12:51 volumio volumio[901]: info: sendMpdCommand play took 2 milliseconds Aug 29 15:12:56 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Aug 29 15:12:56 volumio volumio[901]: info: CoreStateMachine::play index undefined Aug 29 15:12:56 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:12:56 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:56 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:12:56 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:12:56 volumio volumio[901]: info: ControllerMpd::resume Aug 29 15:12:56 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:12:56 volumio volumio[901]: info: sendMpdCommand play took 2 milliseconds Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 15:13:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 15:13:25 volumio volumio[901]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mikhail%20Bennett/After%20I%20Pass%20Away/12e1f4fa-6aaf-460b-b547-4285c42a1eff.jpg' Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:30 volumio volumio[901]: info: Retrieving Cloud Streaming UI Aug 29 15:13:30 volumio volumio[901]: info: Getting Tidal Cloud Configuration Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:30 volumio volumio[901]: info: Getting Qobuz Cloud Configuration Aug 29 15:13:30 volumio volumio[901]: info: Asking plugin for UI Config Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:30 volumio volumio[901]: info: Getting Spotify Cloud Configuration Aug 29 15:13:30 volumio volumio[901]: info: Asking plugin for UI Config Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:30 volumio volumio[901]: info: Saving Spotify Acccount Aug 29 15:13:30 volumio volumio[901]: info: Got Tidal Cloud Configuration Aug 29 15:13:30 volumio volumio[901]: info: Got it Aug 29 15:13:30 volumio volumio[901]: info: Got it Aug 29 15:13:30 volumio volumio[901]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:13:30 volumio volumio[901]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 15:13:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 15:13:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 15:13:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 29 15:13:36 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 15:13:36 volumio volumio[901]: info: CURURI: music-library/USB/06F00E01F00DF827/Music/烟嗓代表 Aug 29 15:13:36 volumio volumio[901]: info: Preload queue cleared Aug 29 15:13:37 volumio volumio[901]: info: Preload queue cleared Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::ClearQueue Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::stop Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::serviceStop Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::serviceStop Aug 29 15:13:37 volumio volumio[901]: info: ControllerMpd::stop Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::clearPlayQueue Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 29 15:13:37 volumio volumio[901]: info: Preload queue cleared Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002tzVBs3pGe6R.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002tzVBs3pGe6R.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::play index 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 29 15:13:37 volumio volumio[901]: info: Preload queue cleared Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000029iLeM0f1Z8D.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F0000029iLeM0f1Z8D.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Letter from Rosie(罗西的来信) - RVAN.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Letter from Rosie(罗西的来信) - RVAN.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Everything I Do - Brandy.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Everything I Do - Brandy.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003AsCIk3AcCKT.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003AsCIk3AcCKT.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002mYF8f1QDlRJ.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002mYF8f1QDlRJ.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002YwZf103yics_1.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002YwZf103yics_1.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000KVeyi2TBhs6.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000KVeyi2TBhs6.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000lFwoH1ET1OS.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000000lFwoH1ET1OS.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003uPmE31vozLt.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003uPmE31vozLt.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003z4jdG31pjpr.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003z4jdG31pjpr.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003yWnv14YFVKP_1.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003yWnv14YFVKP_1.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/A Thousand Kisses Deep - Leonard Cohen.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/A Thousand Kisses Deep - Leonard Cohen.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002fNUO23oxbQy.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002fNUO23oxbQy.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002jfs8G3D0Za0.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002jfs8G3D0Za0.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003b7YKB1NwxdJ.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000003b7YKB1NwxdJ.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002zbsxw0c9LqY.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002zbsxw0c9LqY.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002oDhWY36vE8u.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000002oDhWY36vE8u.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Feeling Good - Michael Bublé.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/Feeling Good - Michael Bublé.flac Aug 29 15:13:37 volumio volumio[901]: info: Adding Item to queue: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001ycVcs0ISuZ5.flac Aug 29 15:13:37 volumio volumio[901]: info: Using cached record of: music-library/USB/06F00E01F00DF827/Music/烟嗓代表/F000001ycVcs0ISuZ5.flac Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::stop Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::play index undefined Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::clearAddPlayTracks USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand stop took 57 milliseconds Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand stop took 8 milliseconds Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 15:13:37 volumio volumio[901]: info: Aug 29 15:13:37 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 29 15:13:37 volumio volumio[901]: info: Ignoring MPD Status Update Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand clear took 2 milliseconds Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac" Aug 29 15:13:37 volumio volumio[901]: error: updateQueue error: null Aug 29 15:13:37 volumio volumio[901]: info: Aug 29 15:13:37 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 29 15:13:37 volumio volumio[901]: info: Ignoring MPD Status Update Aug 29 15:13:37 volumio volumio[901]: info: ------------------------------ 4ms Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac" took 3 milliseconds Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:13:37 volumio volumio[901]: info: ------------------------------ 18ms Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand play took 18 milliseconds Aug 29 15:13:37 volumio volumio[901]: info: Aug 29 15:13:37 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:13:37 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:13:37 volumio volumio[901]: info: Aug 29 15:13:37 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 29 15:13:37 volumio volumio[901]: info: ControllerMpd::getState Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand status took 6 milliseconds Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand status took 7 milliseconds Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:13:37 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:13:37 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:13:37 volumio volumio[901]: info: ------------------------------ 23ms Aug 29 15:13:37 volumio volumio[901]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 29 15:13:37 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:13:37 volumio volumio[901]: info: ControllerMpd::pushState Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"I Kept the Light On","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000001g8l2c1ArFBI.flac","trackType":"flac"} Aug 29 15:13:37 volumio volumio[901]: verbose: CURRENT POSITION 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:13:37 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:13:37 volumio volumio[901]: info: CoreStateMachine::pushState Aug 29 15:13:37 volumio volumio[901]: info: CorePlayQueue::getTrack 1 Aug 29 15:13:37 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 29 15:13:37 volumio volumio[901]: info: ------------------------------ 55ms Aug 29 15:13:38 volumio volumio[901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 15:13:38 volumio volumio[901]: Error: connect ETIMEDOUT 199.59.150.49:443 Aug 29 15:13:38 volumio volumio[901]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 29 15:13:38 volumio volumio[901]: errno: -110, Aug 29 15:13:38 volumio volumio[901]: code: 'ETIMEDOUT', Aug 29 15:13:38 volumio volumio[901]: syscall: 'connect', Aug 29 15:13:38 volumio volumio[901]: address: '199.59.150.49', Aug 29 15:13:38 volumio volumio[901]: port: 443 Aug 29 15:13:38 volumio volumio[901]: } Aug 29 15:13:38 volumio volumio[901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 15:13:38 volumio sudo[2765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 15:12 Aug 29 15:13:38 volumio sudo[2765]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 09:58:18 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="54b3518c7dd8feba4753e0ad124ce801"