-- Logs begin at Tue 2025-12-09 07:59:00 CST, end at Tue 2025-12-09 08:01:41 CST. -- Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::play index undefined Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::clearAddPlayTracks USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand stop took 2 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand clear took 1 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:00 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:00 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" took 3 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:00 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:00 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 10ms Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 10ms Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand play took 7 milliseconds Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 6ms Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 6ms Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:00 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:00 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:00 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 5ms Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:00 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:00 volumio volumio[848]: info: Dec 09 08:00:00 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:00 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand status took 17 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand status took 15 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand status took 13 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand clearerror took 19 milliseconds Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand clearerror took 19 milliseconds Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand clearerror took 18 milliseconds Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:00 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:00 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: No code Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 104ms Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand playlistinfo took 80 milliseconds Dec 09 08:00:00 volumio volumio[848]: info: sendMpdCommand playlistinfo took 79 milliseconds Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:00 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:00 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:00 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: No code Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:00 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: No code Dec 09 08:00:00 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:00 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:00 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 193ms Dec 09 08:00:00 volumio volumio[848]: info: ------------------------------ 189ms Dec 09 08:00:02 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::play index undefined Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 08:00:02 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Dec 09 08:00:02 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::clearAddPlayTracks USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand stop took 2 milliseconds Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:02 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand clear took 1 milliseconds Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:02 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:02 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:02 volumio volumio[848]: error: updateQueue error: null Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:02 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 5ms Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" took 4 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 4ms Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 3ms Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:02 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:02 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 6ms Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand play took 4 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 4ms Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 3ms Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:02 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:02 volumio volumio[848]: info: Dec 09 08:00:02 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:02 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand status took 15 milliseconds Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand status took 17 milliseconds Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand status took 17 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand clearerror took 11 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:02 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:02 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:02 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:02 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:02 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:02 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:02 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:02 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:02 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:02 volumio volumio[848]: info: No code Dec 09 08:00:02 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:02 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:02 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:02 volumio volumio[848]: info: ------------------------------ 112ms Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand clearerror took 92 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand clearerror took 91 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand playlistinfo took 84 milliseconds Dec 09 08:00:02 volumio volumio[848]: info: sendMpdCommand playlistinfo took 68 milliseconds Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:02 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:02 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:03 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:03 volumio volumio[848]: info: No code Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:03 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:03 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:03 volumio volumio[848]: info: No code Dec 09 08:00:03 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:03 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:03 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:03 volumio volumio[848]: info: ------------------------------ 188ms Dec 09 08:00:03 volumio volumio[848]: info: ------------------------------ 182ms Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::play index undefined Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::clearAddPlayTracks USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand stop took 1 milliseconds Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand clear took 0 milliseconds Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:05 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:05 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" took 2 milliseconds Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:05 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:05 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 7ms Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 6ms Dec 09 08:00:05 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 5ms Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 4ms Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:05 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:05 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:05 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:05 volumio volumio[848]: info: Dec 09 08:00:05 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:05 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand status took 17 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 17ms Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand status took 16 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 17ms Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand status took 17 milliseconds Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand clearerror took 15 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand clearerror took 14 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand clearerror took 13 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 09 08:00:05 volumio volumio[848]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:05 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:05 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:05 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: No code Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:05 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: No code Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:05 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: No code Dec 09 08:00:05 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 115ms Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 113ms Dec 09 08:00:05 volumio volumio[848]: info: ------------------------------ 112ms Dec 09 08:00:05 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 09 08:00:10 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 08:00:18 volumio volumio[848]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 09 08:00:18 volumio volumio[848]: info: Preparing to save Alsa Options, stopping services first Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Dec 09 08:00:18 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::volumioPause Dec 09 08:00:18 volumio volumio[848]: info: CoreStateMachine::pause Dec 09 08:00:18 volumio volumio[848]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 08:00:18 volumio volumio[848]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 09 08:00:18 volumio volumio[848]: info: Setting mixer Master for card Analog Out Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 09 08:00:18 volumio volumio[848]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 09 08:00:18 volumio volumio[848]: info: Disabling external Volume Control Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 08:00:18 volumio volumio[848]: info: Preparing to generate the ALSA configuration file Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 08:00:18 volumio volumio[848]: info: Asound.conf file unchanged, so no further update is needed Dec 09 08:00:18 volumio volumio[848]: info: Output device has changed, restarting MPD Dec 09 08:00:18 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 09 08:00:18 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 08:00:18 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Dec 09 08:00:18 volumio volumio[848]: info: Output device has changed, restarting Shairport Sync Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 08:00:18 volumio sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 09 08:00:18 volumio sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 08:00:18 volumio systemd[1]: Stopping Music Player Daemon... Dec 09 08:00:18 volumio volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 09 08:00:18 volumio volumio[848]: info: VolumeController:: Volume=41 Mute =false Dec 09 08:00:18 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:18 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 08:00:18 volumio systemd[1]: mpd.service: Succeeded. Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:18 volumio systemd[1]: Stopped Music Player Daemon. Dec 09 08:00:18 volumio volumio[848]: info: MPD Permissions set Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 08:00:18 volumio systemd[1]: Starting Music Player Daemon... Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 09 08:00:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 08:00:18 volumio volumio[848]: info: Starting Shairport Sync Dec 09 08:00:18 volumio sudo[1922]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 09 08:00:18 volumio sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 08:00:18 volumio sudo[1922]: pam_unix(sudo:session): session closed for user root Dec 09 08:00:18 volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 09 08:00:18 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 08:00:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 09 08:00:18 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 09 08:00:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 09 08:00:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 09 08:00:18 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root Dec 09 08:00:18 volumio volumio[848]: info: Shairport-Sync Started Dec 09 08:00:19 volumio mpd[1932]: Dec 09 08:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 09 08:00:19 volumio systemd[1]: Started Music Player Daemon. Dec 09 08:00:19 volumio sudo[1917]: pam_unix(sudo:session): session closed for user root Dec 09 08:00:19 volumio volumio[848]: error: updateQueue error: null Dec 09 08:00:22 volumio volumio[848]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 09 08:00:22 volumio volumio[848]: info: CoreStateMachine::getcurrentVolume Dec 09 08:00:22 volumio volumio[848]: info: CoreCommandRouter::volumioRetrievevolume Dec 09 08:00:22 volumio volumio[848]: info: VolumeController:: Volume=41 Mute =false Dec 09 08:00:22 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:22 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 08:00:22 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:22 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Dec 09 08:00:22 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Dec 09 08:00:22 volumio volumio[848]: info: CoreCommandRouter::volumioRetrievevolume Dec 09 08:00:22 volumio volumio[848]: info: VolumeController:: Volume=41 Mute =false Dec 09 08:00:22 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:22 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:22 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:25 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Dec 09 08:00:25 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::play index undefined Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 08:00:26 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Dec 09 08:00:26 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::clearAddPlayTracks USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 08:00:26 volumio volumio[848]: info: sendMpdCommand stop took 1 milliseconds Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 08:00:26 volumio volumio[848]: info: Dec 09 08:00:26 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:26 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:26 volumio volumio[848]: info: sendMpdCommand clear took 2 milliseconds Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" Dec 09 08:00:26 volumio volumio[848]: error: updateQueue error: null Dec 09 08:00:26 volumio volumio[848]: info: Dec 09 08:00:26 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:26 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:26 volumio volumio[848]: info: ------------------------------ 6ms Dec 09 08:00:26 volumio volumio[848]: info: sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" took 5 milliseconds Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Dec 09 08:00:26 volumio volumio[848]: info: ------------------------------ 3ms Dec 09 08:00:26 volumio volumio[848]: info: Dec 09 08:00:26 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:26 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:26 volumio volumio[848]: info: sendMpdCommand status took 15 milliseconds Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:26 volumio volumio[848]: info: sendMpdCommand clearerror took 3 milliseconds Dec 09 08:00:26 volumio volumio[848]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 09 08:00:26 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:26 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:26 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:26 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 08:00:26 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:26 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:26 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:26 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:26 volumio volumio[848]: info: No code Dec 09 08:00:26 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:26 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:26 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:26 volumio volumio[848]: info: ------------------------------ 57ms Dec 09 08:00:28 volumio ntpd[1365]: Soliciting pool server 2001:da8:215:4020::118:123 Dec 09 08:00:34 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::play index undefined Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 08:00:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Dec 09 08:00:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::clearAddPlayTracks USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand stop took 1 milliseconds Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 08:00:34 volumio volumio[848]: info: Dec 09 08:00:34 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:34 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand clear took 1 milliseconds Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" Dec 09 08:00:34 volumio volumio[848]: error: updateQueue error: null Dec 09 08:00:34 volumio volumio[848]: info: Dec 09 08:00:34 volumio volumio[848]: ---------------------------- MPD announces system playlist update Dec 09 08:00:34 volumio volumio[848]: info: Ignoring MPD Status Update Dec 09 08:00:34 volumio volumio[848]: info: ------------------------------ 2ms Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand add "USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac" took 2 milliseconds Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Dec 09 08:00:34 volumio volumio[848]: info: ------------------------------ 3ms Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand play took 2 milliseconds Dec 09 08:00:34 volumio volumio[848]: info: Dec 09 08:00:34 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 09 08:00:34 volumio volumio[848]: info: ControllerMpd::getState Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand status took 12 milliseconds Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand clearerror took 4 milliseconds Dec 09 08:00:34 volumio volumio[848]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 09 08:00:34 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 09 08:00:34 volumio volumio[848]: info: ControllerMpd::pushState Dec 09 08:00:34 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:34 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 08:00:34 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:34 volumio volumio[848]: 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 Wooden Prince, Sz. 60 (Final Version): I. Introduction","artist":"BBC Scottish Symphony Orchestra","album":"Bartok: The Wooden Prince (Final Version)","uri":"USB/music/交响乐/好听/BBC Scottish Symphony Orchestra - Bartok- The Wooden Prince (Final Version) (2024) Hi-Res/01. The Wooden Prince, Sz. 60 (Final Version) I. Introduction.flac","trackType":"flac"} Dec 09 08:00:34 volumio volumio[848]: verbose: CURRENT POSITION 0 Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:34 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:34 volumio volumio[848]: info: No code Dec 09 08:00:34 volumio volumio[848]: info: CoreStateMachine::pushState Dec 09 08:00:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:00:34 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 09 08:00:34 volumio volumio[848]: info: ------------------------------ 49ms Dec 09 08:00:38 volumio volumio[848]: info: CoreCommandRouter::volumioGetQueue Dec 09 08:00:38 volumio volumio[848]: info: CoreStateMachine::getQueue Dec 09 08:00:38 volumio volumio[848]: info: CorePlayQueue::getQueue Dec 09 08:00:40 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:00:40 volumio volumio[848]: info: CURURI: music-library Dec 09 08:00:40 volumio volumio[848]: info: Preload queue cleared Dec 09 08:00:42 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:00:42 volumio volumio[848]: info: CURURI: music-library/USB Dec 09 08:00:42 volumio volumio[848]: info: Preload queue cleared Dec 09 08:00:46 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:00:46 volumio volumio[848]: info: CURURI: music-library/USB/music Dec 09 08:00:46 volumio volumio[848]: info: Preload queue cleared Dec 09 08:00:50 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:00:50 volumio volumio[848]: info: CURURI: music-library/USB/music/华语 Dec 09 08:00:50 volumio volumio[848]: info: Preload queue cleared Dec 09 08:01:02 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:01:02 volumio volumio[848]: info: CURURI: music-library/USB/music/交响乐 Dec 09 08:01:02 volumio volumio[848]: info: Preload queue cleared Dec 09 08:01:05 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:01:05 volumio volumio[848]: info: CURURI: music-library/USB/music/交响乐/好听 Dec 09 08:01:05 volumio volumio[848]: info: Preload queue cleared Dec 09 08:01:25 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:01:25 volumio volumio[848]: info: CURURI: music-library/USB/music/交响乐/好听 Dec 09 08:01:25 volumio volumio[848]: info: Preload queue cleared Dec 09 08:01:31 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 09 08:01:31 volumio volumio[848]: info: CURURI: music-library/USB/music/交响乐/好听 Dec 09 08:01:31 volumio volumio[848]: info: Preload queue cleared Dec 09 08:01:34 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Dec 09 08:01:34 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Dec 09 08:01:40 volumio volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 08:01:41 volumio volumio[848]: Error: connect ETIMEDOUT 202.160.130.145:443 Dec 09 08:01:41 volumio volumio[848]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 09 08:01:41 volumio volumio[848]: errno: -110, Dec 09 08:01:41 volumio volumio[848]: code: 'ETIMEDOUT', Dec 09 08:01:41 volumio volumio[848]: syscall: 'connect', Dec 09 08:01:41 volumio volumio[848]: address: '202.160.130.145', Dec 09 08:01:41 volumio volumio[848]: port: 443 Dec 09 08:01:41 volumio volumio[848]: } Dec 09 08:01:41 volumio volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 08:01:41 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 08:00 Dec 09 08:01:41 volumio sudo[2123]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"