-- Logs begin at Sat 2026-08-29 15:44:06 CST, end at Sat 2026-08-29 17:58:46 CST. -- Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioNext Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::next Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::stop Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::stPlaybackTimer Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 1 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::serviceStop Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 1 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::serviceStop Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::stop Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:57:45 volumio volumio[850]: info: Aug 29 17:57:45 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand stop took 28 milliseconds Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::play index undefined Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::clearAddPlayTracks USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand status took 8 milliseconds Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand stop took 4 milliseconds Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 17:57:45 volumio volumio[850]: info: Aug 29 17:57:45 volumio volumio[850]: ---------------------------- MPD announces system playlist update Aug 29 17:57:45 volumio volumio[850]: info: Ignoring MPD Status Update Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand clear took 5 milliseconds Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac" Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"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 17:57:45 volumio volumio[850]: verbose: CURRENT POSITION 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::syncState stateService stop Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:45 volumio volumio[850]: info: No code Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:45 volumio volumio[850]: info: ------------------------------ 69ms Aug 29 17:57:45 volumio volumio[850]: error: updateQueue error: null Aug 29 17:57:45 volumio volumio[850]: info: Aug 29 17:57:45 volumio volumio[850]: ---------------------------- MPD announces system playlist update Aug 29 17:57:45 volumio volumio[850]: info: Ignoring MPD Status Update Aug 29 17:57:45 volumio volumio[850]: info: ------------------------------ 59ms Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac" took 56 milliseconds Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand play Aug 29 17:57:45 volumio volumio[850]: info: Aug 29 17:57:45 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:45 volumio volumio[850]: info: ------------------------------ 12ms Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand play took 11 milliseconds Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:45 volumio volumio[850]: info: Aug 29 17:57:45 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand status took 6 milliseconds Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand status took 6 milliseconds Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Woman I Lost","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac","trackType":"flac"} Aug 29 17:57:45 volumio volumio[850]: verbose: CURRENT POSITION 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:57:45 volumio volumio[850]: info: ------------------------------ 20ms Aug 29 17:57:45 volumio volumio[850]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 29 17:57:45 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:45 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"348 Kbps","isStreaming":false,"title":"The Woman I Lost","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac","trackType":"flac"} Aug 29 17:57:45 volumio volumio[850]: verbose: CURRENT POSITION 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:57:45 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:45 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:45 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:45 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:45 volumio volumio[850]: info: ------------------------------ 102ms Aug 29 17:57:48 volumio volumio[850]: 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/3385b978-c535-4e93-a771-0c8790b4d557.jpg' Aug 29 17:57:49 volumio volumio[850]: info: CoreCommandRouter::volumioSeek Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::seek Aug 29 17:57:49 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:49 volumio volumio[850]: info: TRACKBLOCK {"uri":"mnt/USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac","service":"mpd","name":"The Woman I Lost","artist":"Mikhail Bennett","album":"After I Pass Away","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=451&web=Mikhail%20Bennett/After%20I%20Pass%20Away/extralarge&path=%2FUSB%2F06F00E01F00DF827%2FMusic%2F%E7%83%9F%E5%97%93%E4%BB%A3%E8%A1%A8&metadata=false","duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:57:49 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:49 volumio volumio[850]: info: ControllerMpd::seek Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:49 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:49 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:49 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:49 volumio volumio[850]: error: null Aug 29 17:57:49 volumio volumio[850]: info: Aug 29 17:57:49 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:49 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:49 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:49 volumio volumio[850]: info: sendMpdCommand status took 3 milliseconds Aug 29 17:57:49 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:49 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:49 volumio volumio[850]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 29 17:57:49 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:49 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:49 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:49 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:49 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71000,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"404 Kbps","isStreaming":false,"title":"The Woman I Lost","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac","trackType":"flac"} Aug 29 17:57:49 volumio volumio[850]: verbose: CURRENT POSITION 2 Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:57:49 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:49 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:49 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:49 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:49 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:49 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:49 volumio volumio[850]: info: ------------------------------ 52ms Aug 29 17:57:50 volumio volumio[850]: info: CoreCommandRouter::volumioNext Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::next Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::stop Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::stPlaybackTimer Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Aug 29 17:57:50 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:50 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:50 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:50 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::serviceStop Aug 29 17:57:50 volumio volumio[850]: info: CorePlayQueue::getTrack 2 Aug 29 17:57:50 volumio volumio[850]: info: CoreCommandRouter::serviceStop Aug 29 17:57:50 volumio volumio[850]: info: ControllerMpd::stop Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:57:50 volumio volumio[850]: info: Aug 29 17:57:50 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:50 volumio volumio[850]: info: sendMpdCommand stop took 21 milliseconds Aug 29 17:57:50 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::play index undefined Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:57:50 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:57:50 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::clearAddPlayTracks USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:57:50 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Aug 29 17:57:50 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Aug 29 17:57:50 volumio volumio[850]: info: sendMpdCommand status took 4 milliseconds Aug 29 17:57:50 volumio volumio[850]: info: sendMpdCommand stop took 2 milliseconds Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:50 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 17:57:51 volumio volumio[850]: info: Aug 29 17:57:51 volumio volumio[850]: ---------------------------- MPD announces system playlist update Aug 29 17:57:51 volumio volumio[850]: info: Ignoring MPD Status Update Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand clear took 4 milliseconds Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac" Aug 29 17:57:51 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Woman I Lost","artist":"Mikhail Bennett","album":"After I Pass Away","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F000003ozwyC1teZtP.flac","trackType":"flac"} Aug 29 17:57:51 volumio volumio[850]: verbose: CURRENT POSITION 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::syncState stateService stop Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:51 volumio volumio[850]: info: No code Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:51 volumio volumio[850]: info: ------------------------------ 54ms Aug 29 17:57:51 volumio volumio[850]: error: updateQueue error: null Aug 29 17:57:51 volumio volumio[850]: info: Aug 29 17:57:51 volumio volumio[850]: ---------------------------- MPD announces system playlist update Aug 29 17:57:51 volumio volumio[850]: info: Ignoring MPD Status Update Aug 29 17:57:51 volumio volumio[850]: info: ------------------------------ 48ms Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac" took 45 milliseconds Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand play Aug 29 17:57:51 volumio volumio[850]: info: Aug 29 17:57:51 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:51 volumio volumio[850]: info: ------------------------------ 20ms Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand play took 18 milliseconds Aug 29 17:57:51 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:51 volumio volumio[850]: info: Aug 29 17:57:51 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:51 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand status took 27 milliseconds Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand status took 16 milliseconds Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:51 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sailing","artist":"Rod Stewart","album":"Atlantic Crossing","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac","trackType":"flac"} Aug 29 17:57:51 volumio volumio[850]: verbose: CURRENT POSITION 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:57:51 volumio volumio[850]: info: ------------------------------ 49ms Aug 29 17:57:51 volumio volumio[850]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 29 17:57:51 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:51 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"Sailing","artist":"Rod Stewart","album":"Atlantic Crossing","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac","trackType":"flac"} Aug 29 17:57:51 volumio volumio[850]: verbose: CURRENT POSITION 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:57:51 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:51 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:51 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:51 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:51 volumio volumio[850]: info: ------------------------------ 127ms Aug 29 17:57:53 volumio volumio[850]: info: CoreCommandRouter::volumioSeek Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::seek Aug 29 17:57:53 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:53 volumio volumio[850]: info: TRACKBLOCK {"uri":"mnt/USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac","service":"mpd","name":"Sailing","artist":"Rod Stewart","album":"Atlantic Crossing","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=451&web=Rod%20Stewart/Atlantic%20Crossing/extralarge&path=%2FUSB%2F06F00E01F00DF827%2FMusic%2F%E7%83%9F%E5%97%93%E4%BB%A3%E8%A1%A8&metadata=false","duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:57:53 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:53 volumio volumio[850]: info: ControllerMpd::seek Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:53 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:53 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:53 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:53 volumio volumio[850]: error: null Aug 29 17:57:53 volumio volumio[850]: info: Aug 29 17:57:53 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:53 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:53 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:53 volumio volumio[850]: info: sendMpdCommand status took 0 milliseconds Aug 29 17:57:53 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:53 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:53 volumio volumio[850]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 17:57:53 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:53 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:53 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:53 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:53 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"835 Kbps","isStreaming":false,"title":"Sailing","artist":"Rod Stewart","album":"Atlantic Crossing","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac","trackType":"flac"} Aug 29 17:57:53 volumio volumio[850]: verbose: CURRENT POSITION 3 Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:57:53 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:53 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:53 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:53 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:53 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:53 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:53 volumio volumio[850]: info: ------------------------------ 40ms Aug 29 17:57:55 volumio volumio[850]: info: CoreCommandRouter::volumioNext Aug 29 17:57:55 volumio volumio[850]: info: CoreStateMachine::next Aug 29 17:57:55 volumio volumio[850]: info: CoreStateMachine::stop Aug 29 17:57:55 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:57:55 volumio volumio[850]: info: CoreStateMachine::stPlaybackTimer Aug 29 17:57:55 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Aug 29 17:57:55 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Aug 29 17:57:55 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:55 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:55 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::serviceStop Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 3 Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::serviceStop Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::stop Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:57:56 volumio volumio[850]: info: Aug 29 17:57:56 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand stop took 18 milliseconds Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::play index undefined Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::clearAddPlayTracks USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand status took 4 milliseconds Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand stop took 2 milliseconds Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 17:57:56 volumio volumio[850]: info: Aug 29 17:57:56 volumio volumio[850]: ---------------------------- MPD announces system playlist update Aug 29 17:57:56 volumio volumio[850]: info: Ignoring MPD Status Update Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand clear took 3 milliseconds Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac" Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sailing","artist":"Rod Stewart","album":"Atlantic Crossing","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/Sailing - Rod Stewart.flac","trackType":"flac"} Aug 29 17:57:56 volumio volumio[850]: verbose: CURRENT POSITION 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::syncState stateService stop Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:56 volumio volumio[850]: info: No code Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:56 volumio volumio[850]: info: ------------------------------ 42ms Aug 29 17:57:56 volumio volumio[850]: error: updateQueue error: null Aug 29 17:57:56 volumio volumio[850]: info: Aug 29 17:57:56 volumio volumio[850]: ---------------------------- MPD announces system playlist update Aug 29 17:57:56 volumio volumio[850]: info: Ignoring MPD Status Update Aug 29 17:57:56 volumio volumio[850]: info: ------------------------------ 36ms Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand add "USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac" took 34 milliseconds Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand play Aug 29 17:57:56 volumio volumio[850]: info: Aug 29 17:57:56 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:56 volumio volumio[850]: info: ------------------------------ 22ms Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand play took 20 milliseconds Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:56 volumio volumio[850]: info: Aug 29 17:57:56 volumio volumio[850]: ---------------------------- MPD announces state update: player Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::getState Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand status took 13 milliseconds Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand status took 6 milliseconds Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::parseState Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Move","artist":"Keb' Mo'","album":"Bluesamericana","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac","trackType":"flac"} Aug 29 17:57:56 volumio volumio[850]: verbose: CURRENT POSITION 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:57:56 volumio volumio[850]: info: ------------------------------ 27ms Aug 29 17:57:56 volumio volumio[850]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 17:57:56 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:57:56 volumio volumio[850]: info: ControllerMpd::pushState Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::servicePushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"767 Kbps","isStreaming":false,"title":"Move","artist":"Keb' Mo'","album":"Bluesamericana","uri":"USB/06F00E01F00DF827/Music/烟嗓代表/F0000021aOrO3A561b.flac","trackType":"flac"} Aug 29 17:57:56 volumio volumio[850]: verbose: CURRENT POSITION 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:57:56 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:56 volumio volumio[850]: info: CoreStateMachine::pushState Aug 29 17:57:56 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:57:56 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Aug 29 17:57:56 volumio volumio[850]: info: ------------------------------ 45ms Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 17:58:08 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 17:58:26 volumio volumio[850]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 29 17:58:26 volumio sudo[20611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 17:58:26 volumio sudo[20611]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:26 volumio sudo[20611]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:26 volumio volumio[850]: info: MPD Permissions set Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio sudo[20614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 17:58:26 volumio sudo[20614]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:26 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 17:58:26 volumio systemd[1]: mpd.service: Succeeded. Aug 29 17:58:26 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 17:58:26 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:26 volumio systemd[1]: Starting Music Player Daemon... Aug 29 17:58:26 volumio sudo[20622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 17:58:26 volumio sudo[20622]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:26 volumio sudo[20622]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:27 volumio mpd[20624]: Aug 29 17:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 17:58:27 volumio systemd[1]: Started Music Player Daemon. Aug 29 17:58:27 volumio sudo[20614]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:27 volumio volumio[850]: error: updateQueue error: null Aug 29 17:58:37 volumio volumio[850]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 29 17:58:37 volumio sudo[20643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 17:58:37 volumio sudo[20643]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:37 volumio sudo[20643]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:37 volumio volumio[850]: info: MPD Permissions set Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio sudo[20646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 17:58:37 volumio sudo[20646]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 17:58:37 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 17:58:37 volumio systemd[1]: mpd.service: Succeeded. Aug 29 17:58:37 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 17:58:37 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:37 volumio systemd[1]: Starting Music Player Daemon... Aug 29 17:58:37 volumio sudo[20654]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 17:58:37 volumio sudo[20654]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:37 volumio sudo[20654]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:38 volumio mpd[20656]: Aug 29 17:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 17:58:38 volumio systemd[1]: Started Music Player Daemon. Aug 29 17:58:38 volumio sudo[20646]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:38 volumio volumio[850]: error: updateQueue error: null Aug 29 17:58:39 volumio volumio[850]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 29 17:58:39 volumio sudo[20721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 17:58:39 volumio sudo[20721]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:39 volumio sudo[20721]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio sudo[20724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 17:58:39 volumio sudo[20724]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 17:58:39 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 17:58:39 volumio systemd[1]: mpd.service: Succeeded. Aug 29 17:58:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 17:58:39 volumio systemd[1]: Starting Music Player Daemon... Aug 29 17:58:39 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 17:58:39 volumio volumio[850]: info: MPD Permissions set Aug 29 17:58:39 volumio sudo[20730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 17:58:39 volumio sudo[20730]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 17:58:39 volumio sudo[20730]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:40 volumio mpd[20735]: Aug 29 17:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 17:58:40 volumio systemd[1]: Started Music Player Daemon. Aug 29 17:58:40 volumio sudo[20724]: pam_unix(sudo:session): session closed for user root Aug 29 17:58:40 volumio volumio[850]: error: updateQueue error: null Aug 29 17:58:41 volumio volumio[850]: info: CoreCommandRouter::volumioPause Aug 29 17:58:41 volumio volumio[850]: info: CoreStateMachine::pause Aug 29 17:58:41 volumio volumio[850]: info: CoreStateMachine::stPlaybackTimer Aug 29 17:58:41 volumio volumio[850]: info: CoreStateMachine::servicePause Aug 29 17:58:41 volumio volumio[850]: info: CorePlayQueue::getTrack 4 Aug 29 17:58:41 volumio volumio[850]: info: CoreCommandRouter::servicePause Aug 29 17:58:41 volumio volumio[850]: info: ControllerMpd::pause Aug 29 17:58:41 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 17:58:41 volumio volumio[850]: info: sendMpdCommand pause took 5 milliseconds Aug 29 17:58:44 volumio volumio[850]: info: CoreCommandRouter::volumioPause Aug 29 17:58:44 volumio volumio[850]: info: CoreStateMachine::pause Aug 29 17:58:46 volumio volumio[850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 17:58:46 volumio volumio[850]: Error: connect ETIMEDOUT 74.86.151.167:443 Aug 29 17:58:46 volumio volumio[850]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 29 17:58:46 volumio volumio[850]: errno: -110, Aug 29 17:58:46 volumio volumio[850]: code: 'ETIMEDOUT', Aug 29 17:58:46 volumio volumio[850]: syscall: 'connect', Aug 29 17:58:46 volumio volumio[850]: address: '74.86.151.167', Aug 29 17:58:46 volumio volumio[850]: port: 443 Aug 29 17:58:46 volumio volumio[850]: } Aug 29 17:58:46 volumio volumio[850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 17:58:46 volumio sudo[20747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 17:57 Aug 29 17:58:46 volumio sudo[20747]: 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"