-- Logs begin at Sun 2025-05-04 14:13:29 +07, end at Sun 2025-05-04 15:53:34 +07. -- May 04 15:52:05 volumio volumio[7692]: info: CoreCommandRouter::volumioPrevious May 04 15:52:05 volumio volumio[7692]: info: CoreStateMachine::previous May 04 15:52:05 volumio volumio[7692]: info: CoreStateMachine::updateTrackBlock May 04 15:52:05 volumio volumio[7692]: info: CorePlayQueue::getTrackBlock May 04 15:52:05 volumio volumio[7692]: info: CoreStateMachine::serviceClearAddPlay May 04 15:52:05 volumio volumio[7692]: info: CoreCommandRouter::serviceClearAddPlayTracks May 04 15:52:05 volumio volumio[7692]: error: WARNING: No clearAddPlayTracks method for service mpd May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::volumioNext May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::next May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::stop May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::updateTrackBlock May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrackBlock May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::stPlaybackTimer May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::serviceStop May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::serviceStop May 04 15:52:07 volumio volumio[7692]: info: ControllerMpd::stop May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand stop May 04 15:52:07 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand stop took 7 milliseconds May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::play index undefined May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::startPlaybackTimer May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::clearAddPlayTracks USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf May 04 15:52:07 volumio volumio[7692]: info: Setting Volume to 100 automatically for DSD May 04 15:52:07 volumio volumio[7692]: info: VolumeController::SetAlsaVolume100 May 04 15:52:07 volumio sudo[4730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:07 volumio sudo[4730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand stop May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio sudo[4730]: pam_unix(sudo:session): session closed for user root May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::updateTrackBlock May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrackBlock May 04 15:52:07 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand stop took 8 milliseconds May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clear May 04 15:52:07 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:07 volumio volumio[7692]: info: May 04 15:52:07 volumio volumio[7692]: ---------------------------- MPD announces system playlist update May 04 15:52:07 volumio volumio[7692]: info: Ignoring MPD Status Update May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand clear took 0 milliseconds May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand add "USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf" May 04 15:52:07 volumio volumio[7692]: error: updateQueue error: null May 04 15:52:07 volumio volumio[7692]: info: May 04 15:52:07 volumio volumio[7692]: ---------------------------- MPD announces system playlist update May 04 15:52:07 volumio volumio[7692]: info: Ignoring MPD Status Update May 04 15:52:07 volumio volumio[7692]: info: ------------------------------ 1ms May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand add "USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf" took 0 milliseconds May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand play May 04 15:52:07 volumio volumio[7692]: info: ------------------------------ 0ms May 04 15:52:07 volumio sudo[4734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:07 volumio sudo[4734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:07 volumio sudo[4734]: pam_unix(sudo:session): session closed for user root May 04 15:52:07 volumio volumio[7692]: info: May 04 15:52:07 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand play took 6 milliseconds May 04 15:52:07 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:52:07 volumio volumio[7692]: info: May 04 15:52:07 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:52:07 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:52:07 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand status took 2 milliseconds May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand status took 1 milliseconds May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand clearerror took 1 milliseconds May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand clearerror took 1 milliseconds May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 15:52:07 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:52:07 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:52:07 volumio volumio[7692]: info: ControllerMpd::pushState May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::servicePushState May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio volumio[7692]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":217,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A2. Twilight","artist":"ELO","album":"Jet Records - 25AP 2111 Japan","uri":"USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf","trackType":"dsf"} May 04 15:52:07 volumio volumio[7692]: verbose: CURRENT POSITION 1 May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::syncState stateService pause May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::syncState currentStatus stop May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:07 volumio volumio[7692]: info: ControllerMpd::pushState May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::servicePushState May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio volumio[7692]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":217,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A2. Twilight","artist":"ELO","album":"Jet Records - 25AP 2111 Japan","uri":"USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf","trackType":"dsf"} May 04 15:52:07 volumio volumio[7692]: verbose: CURRENT POSITION 1 May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::syncState stateService pause May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::syncState currentStatus stop May 04 15:52:07 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:07 volumio volumio[7692]: info: CorePlayQueue::getTrack 1 May 04 15:52:07 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:07 volumio volumio[7692]: info: ------------------------------ 5ms May 04 15:52:07 volumio volumio[7692]: info: ------------------------------ 5ms May 04 15:52:07 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:07 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:07 volumio sudo[4747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:07 volumio sudo[4747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:07 volumio sudo[4747]: pam_unix(sudo:session): session closed for user root May 04 15:52:07 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:07 volumio sudo[4750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:07 volumio sudo[4750]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:07 volumio sudo[4750]: pam_unix(sudo:session): session closed for user root May 04 15:52:07 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:08 volumio volumio[7692]: info: CoreCommandRouter::volumioPrevious May 04 15:52:08 volumio volumio[7692]: info: CoreStateMachine::previous May 04 15:52:08 volumio volumio[7692]: info: CoreStateMachine::updateTrackBlock May 04 15:52:08 volumio volumio[7692]: info: CorePlayQueue::getTrackBlock May 04 15:52:08 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:08 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:08 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 15:52:08 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:08 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:08 volumio sudo[5434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:08 volumio sudo[5434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:08 volumio sudo[5434]: pam_unix(sudo:session): session closed for user root May 04 15:52:08 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::volumioPlay May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::play index undefined May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::startPlaybackTimer May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::clearAddPlayTracks USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf May 04 15:52:19 volumio volumio[7692]: info: Setting Volume to 100 automatically for DSD May 04 15:52:19 volumio volumio[7692]: info: VolumeController::SetAlsaVolume100 May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand stop May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:19 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:19 volumio volumio[7692]: info: May 04 15:52:19 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand stop took 9 milliseconds May 04 15:52:19 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clear May 04 15:52:19 volumio volumio[7692]: info: May 04 15:52:19 volumio volumio[7692]: ---------------------------- MPD announces system playlist update May 04 15:52:19 volumio volumio[7692]: info: Ignoring MPD Status Update May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand status took 0 milliseconds May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand clear took 0 milliseconds May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand add "USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf" May 04 15:52:19 volumio volumio[7692]: error: updateQueue error: null May 04 15:52:19 volumio volumio[7692]: info: May 04 15:52:19 volumio volumio[7692]: ---------------------------- MPD announces system playlist update May 04 15:52:19 volumio volumio[7692]: info: Ignoring MPD Status Update May 04 15:52:19 volumio sudo[10345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:19 volumio sudo[10345]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:19 volumio volumio[7692]: info: ------------------------------ 1ms May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand add "USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf" took 0 milliseconds May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand play May 04 15:52:19 volumio volumio[7692]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 04 15:52:19 volumio volumio[7692]: info: ------------------------------ 3ms May 04 15:52:19 volumio sudo[10345]: pam_unix(sudo:session): session closed for user root May 04 15:52:19 volumio volumio[7692]: info: May 04 15:52:19 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:52:19 volumio volumio[7692]: info: ------------------------------ 8ms May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand play took 8 milliseconds May 04 15:52:19 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:52:19 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:19 volumio volumio[7692]: info: May 04 15:52:19 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:52:19 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand status took 2 milliseconds May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand status took 2 milliseconds May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand clearerror took 1 milliseconds May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:52:19 volumio volumio[7692]: info: ControllerMpd::pushState May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::servicePushState May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":75,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1. Prologue","artist":"ELO","album":"Jet Records - 25AP 2111 Japan","uri":"USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf","trackType":"dsf"} May 04 15:52:19 volumio volumio[7692]: verbose: CURRENT POSITION 0 May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::syncState stateService pause May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::syncState currentStatus stop May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:19 volumio volumio[7692]: info: ------------------------------ 4ms May 04 15:52:19 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand clearerror took 8 milliseconds May 04 15:52:19 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 8 milliseconds May 04 15:52:19 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:52:19 volumio volumio[7692]: info: ControllerMpd::pushState May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::servicePushState May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":75,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1. Prologue","artist":"ELO","album":"Jet Records - 25AP 2111 Japan","uri":"USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf","trackType":"dsf"} May 04 15:52:19 volumio volumio[7692]: verbose: CURRENT POSITION 0 May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::syncState stateService pause May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::syncState currentStatus stop May 04 15:52:19 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:52:19 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:19 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:52:19 volumio volumio[7692]: info: ------------------------------ 12ms May 04 15:52:19 volumio sudo[10355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:19 volumio sudo[10355]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:19 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:52:19 volumio sudo[10355]: pam_unix(sudo:session): session closed for user root May 04 15:52:19 volumio sudo[10358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:52:19 volumio sudo[10358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:52:19 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:19 volumio sudo[10358]: pam_unix(sudo:session): session closed for user root May 04 15:52:19 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 04 15:52:29 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 15:52:42 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 15:52:42 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 04 15:52:56 volumio volumio[7692]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::volumioGetVisibleSources May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::volumioGetState May 04 15:52:56 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 04 15:52:56 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 15:52:57 volumio volumio[7692]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 04 15:52:57 volumio volumio[7692]: info: CoreCommandRouter::volumioGetState May 04 15:52:57 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:57 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 15:52:57 volumio volumio[7692]: info: Listing playlists May 04 15:52:57 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 04 15:52:57 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 04 15:52:57 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 04 15:52:57 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 15:52:59 volumio volumio[7692]: info: Received Get System Info May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 15:52:59 volumio volumio[7692]: info: Discovery: Getting this device information May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::volumioGetState May 04 15:52:59 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 15:52:59 volumio volumio[7692]: info: Received Get System Info May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 15:52:59 volumio volumio[7692]: info: Discovery: Getting this device information May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::volumioGetState May 04 15:52:59 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:52:59 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 15:53:01 volumio volumio[7692]: info: CoreCommandRouter::volumioGetVisibleSources May 04 15:53:01 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 15:53:01 volumio volumio[7692]: info: CoreCommandRouter::volumioGetQueue May 04 15:53:01 volumio volumio[7692]: info: CoreStateMachine::getQueue May 04 15:53:01 volumio volumio[7692]: info: CorePlayQueue::getQueue May 04 15:53:02 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:02 volumio volumio[7692]: info: CURURI: music-library May 04 15:53:02 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:03 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:03 volumio volumio[7692]: info: CURURI: music-library/USB May 04 15:53:03 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:04 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:04 volumio volumio[7692]: info: CURURI: music-library/USB/DATA_ORIGINAL May 04 15:53:04 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:06 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:06 volumio volumio[7692]: info: CURURI: music-library/USB/DATA_ORIGINAL/_DEN May 04 15:53:06 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:07 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:07 volumio volumio[7692]: info: CURURI: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO May 04 15:53:07 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:09 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:09 volumio volumio[7692]: info: CURURI: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO May 04 15:53:09 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:12 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 15:53:12 volumio volumio[7692]: info: CURURI: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128 May 04 15:53:12 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:15 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::ClearQueue May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::stop May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::clearPlayQueue May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::saveQueue May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPushQueue May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::addQueueItems May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::addQueueItems May 04 15:53:15 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPushQueue May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::saveQueue May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::updateTrackBlock May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrackBlock May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPlay May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::play index 0 May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::addQueueItems May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::addQueueItems May 04 15:53:15 volumio volumio[7692]: info: Preload queue cleared May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A2. Twilight.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A3. Yours Truly, 2095.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A3. Yours Truly, 2095.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A4. Ticket To The Moon.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A4. Ticket To The Moon.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A5. The Way Life's Meant To Be.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A5. The Way Life's Meant To Be.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A6. Another Heart Breaks.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A6. Another Heart Breaks.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B1. Rain Is Falling.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B1. Rain Is Falling.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B2. From The End Of The World.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B2. From The End Of The World.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B3. The Lights Go Down.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B3. The Lights Go Down.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B4. Here Is The News.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B4. Here Is The News.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B5. 21st Century Man.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B5. 21st Century Man.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B6. Hold On Tight.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B6. Hold On Tight.dsf May 04 15:53:15 volumio volumio[7692]: info: Adding Item to queue: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B7. Epilogue.dsf May 04 15:53:15 volumio volumio[7692]: info: Using cached record of: music-library/USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/B7. Epilogue.dsf May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::stop May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPushQueue May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::saveQueue May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::play index undefined May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::updateTrackBlock May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrackBlock May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::startPlaybackTimer May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::clearAddPlayTracks USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf May 04 15:53:15 volumio volumio[7692]: info: Setting Volume to 100 automatically for DSD May 04 15:53:15 volumio volumio[7692]: info: VolumeController::SetAlsaVolume100 May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand stop May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:53:15 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:53:15 volumio sudo[2847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:53:15 volumio sudo[2847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:15 volumio volumio[7692]: info: May 04 15:53:15 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand stop took 10 milliseconds May 04 15:53:15 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clear May 04 15:53:15 volumio volumio[7692]: info: May 04 15:53:15 volumio volumio[7692]: ---------------------------- MPD announces system playlist update May 04 15:53:15 volumio volumio[7692]: info: Ignoring MPD Status Update May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand status took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand clear took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand add "USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf" May 04 15:53:15 volumio sudo[2847]: pam_unix(sudo:session): session closed for user root May 04 15:53:15 volumio volumio[7692]: error: updateQueue error: null May 04 15:53:15 volumio volumio[7692]: info: May 04 15:53:15 volumio volumio[7692]: ---------------------------- MPD announces system playlist update May 04 15:53:15 volumio volumio[7692]: info: Ignoring MPD Status Update May 04 15:53:15 volumio volumio[7692]: info: ------------------------------ 5ms May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 5 milliseconds May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand add "USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf" took 5 milliseconds May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand play May 04 15:53:15 volumio volumio[7692]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 04 15:53:15 volumio volumio[7692]: info: ------------------------------ 7ms May 04 15:53:15 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:53:15 volumio volumio[7692]: info: May 04 15:53:15 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:53:15 volumio volumio[7692]: info: ------------------------------ 1ms May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand play took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:53:15 volumio volumio[7692]: info: May 04 15:53:15 volumio volumio[7692]: ---------------------------- MPD announces state update: player May 04 15:53:15 volumio volumio[7692]: info: ControllerMpd::getState May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand status May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand status took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand status took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::parseState May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand clearerror took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand clearerror took 1 milliseconds May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 15:53:15 volumio volumio[7692]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:53:15 volumio volumio[7692]: verbose: ControllerMpd::parseTrackInfo May 04 15:53:15 volumio volumio[7692]: info: ControllerMpd::pushState May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::servicePushState May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":75,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1. Prologue","artist":"ELO","album":"Jet Records - 25AP 2111 Japan","uri":"USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf","trackType":"dsf"} May 04 15:53:15 volumio volumio[7692]: verbose: CURRENT POSITION 0 May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::syncState stateService pause May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::syncState currentStatus stop May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:53:15 volumio volumio[7692]: info: ControllerMpd::pushState May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::servicePushState May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":75,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1. Prologue","artist":"ELO","album":"Jet Records - 25AP 2111 Japan","uri":"USB/DATA_ORIGINAL/_DEN/_ElECTRO/ELO/1981 ELO - Time DSD128/A1. Prologue.dsf","trackType":"dsf"} May 04 15:53:15 volumio volumio[7692]: verbose: CURRENT POSITION 0 May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::syncState stateService pause May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::syncState currentStatus stop May 04 15:53:15 volumio volumio[7692]: info: CoreStateMachine::pushState May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioPushState May 04 15:53:15 volumio volumio[7692]: info: ------------------------------ 6ms May 04 15:53:15 volumio volumio[7692]: info: ------------------------------ 5ms May 04 15:53:15 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:53:15 volumio volumio[7692]: info: PeppyMeterBasic ---peppymeterbasic status stop May 04 15:53:15 volumio sudo[2857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:53:15 volumio sudo[2857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:15 volumio sudo[2860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 04 15:53:15 volumio sudo[2860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:15 volumio sudo[2857]: pam_unix(sudo:session): session closed for user root May 04 15:53:15 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:53:15 volumio sudo[2860]: pam_unix(sudo:session): session closed for user root May 04 15:53:15 volumio volumio[7692]: info: peppymeterbasic Daemon Stop May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 15:53:15 volumio volumio[7692]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 04 15:53:15 volumio volumio[7692]: info: Received Get System Version May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 15:53:15 volumio volumio[7692]: info: Received Get System Info May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 15:53:15 volumio volumio[7692]: info: Discovery: Getting this device information May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::volumioGetState May 04 15:53:15 volumio volumio[7692]: info: CorePlayQueue::getTrack 0 May 04 15:53:15 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 15:53:27 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 15:53:27 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 04 15:53:34 volumio volumio[7692]: info: Starting Uninstall of plugin audio_interface - fusiondsp May 04 15:53:34 volumio volumio[7692]: info: Uninstalling plugin fusiondsp May 04 15:53:34 volumio volumio[7692]: info: Disabling plugin fusiondsp May 04 15:53:34 volumio volumio[7692]: info: Preparing to generate the ALSA configuration file May 04 15:53:34 volumio volumio[7692]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 04 15:53:34 volumio volumio[7692]: info: Reading ALSA contributions from plugins. May 04 15:53:34 volumio volumio[7692]: info: Asound.conf file unchanged, so no further update is needed May 04 15:53:34 volumio volumio[7692]: info: Output device has changed, restarting MPD May 04 15:53:34 volumio sudo[11768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 15:53:34 volumio sudo[11768]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:34 volumio sudo[11768]: pam_unix(sudo:session): session closed for user root May 04 15:53:34 volumio volumio[7692]: info: Checking if uninstall.sh is present May 04 15:53:34 volumio volumio[7692]: info: Executing uninstall.sh May 04 15:53:34 volumio sudo[11771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 15:53:34 volumio sudo[11771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:34 volumio systemd[1]: Stopping Music Player Daemon... May 04 15:53:34 volumio volumio[7692]: info: MPD Permissions set May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio systemd[1]: mpd.service: Succeeded. May 04 15:53:34 volumio systemd[1]: Stopped Music Player Daemon. May 04 15:53:34 volumio sudo[11775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh May 04 15:53:34 volumio sudo[11775]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 15:53:34 volumio systemd[1]: Starting Music Player Daemon... May 04 15:53:34 volumio sudo[11793]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /lib/systemd/system/fusiondsp.service May 04 15:53:34 volumio sudo[11793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:34 volumio sudo[11793]: pam_unix(sudo:session): session closed for user root May 04 15:53:34 volumio sudo[11795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 04 15:53:34 volumio sudo[11795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:34 volumio systemd[1]: Reloading. May 04 15:53:34 volumio sudo[11777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 04 15:53:34 volumio sudo[11777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 15:53:34 volumio sudo[11777]: pam_unix(sudo:session): session closed for user root May 04 15:53:34 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 04 15:53:34 volumio sudo[11795]: pam_unix(sudo:session): session closed for user root May 04 15:53:34 volumio sudo[11775]: pam_unix(sudo:session): session closed for user root May 04 15:53:34 volumio volumio[7692]: info: Uninstall script completed May 04 15:53:34 volumio volumio[7692]: info: Removing plugin fusiondsp from configuration May 04 15:53:34 volumio volumio[7692]: info: Successfully removed fusiondsp configuration files May 04 15:53:34 volumio volumio[7692]: info: Plugin folders cleanup May 04 15:53:34 volumio volumio[7692]: info: Scanning into folder /volumio/app/plugins/ May 04 15:53:34 volumio volumio[7692]: info: Scanning category audio_interface May 04 15:53:34 volumio volumio[7692]: info: Scanning category miscellanea May 04 15:53:34 volumio volumio[7692]: info: Scanning category music_service May 04 15:53:34 volumio volumio[7692]: info: Scanning category plugins.json May 04 15:53:34 volumio volumio[7692]: info: Scanning category system_controller May 04 15:53:34 volumio volumio[7692]: info: Scanning category user_interface May 04 15:53:34 volumio volumio[7692]: info: Scanning into folder /data/plugins/ May 04 15:53:34 volumio volumio[7692]: info: Scanning category audio_interface May 04 15:53:34 volumio volumio[7692]: info: Cleaning folder for fusiondsp May 04 15:53:34 volumio volumio[7692]: info: Scanning category music_service May 04 15:53:34 volumio volumio[7692]: info: Scanning category user_interface May 04 15:53:34 volumio volumio[7692]: info: Plugin folders cleanup completed May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 15:53:34 volumio volumio[7692]: info: [{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.2.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.12","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.1","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true}] May 04 15:53:34 volumio volumio[7692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 04 15:53:34 volumio volumio[7692]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 15:53:34 volumio volumio[7692]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 04 15:53:34 volumio volumio[7692]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) May 04 15:53:34 volumio volumio[7692]: at onErrorNT (internal/child_process.js:472:16) May 04 15:53:34 volumio volumio[7692]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { May 04 15:53:34 volumio volumio[7692]: errno: -2, May 04 15:53:34 volumio volumio[7692]: code: 'ENOENT', May 04 15:53:34 volumio volumio[7692]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 04 15:53:34 volumio volumio[7692]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 04 15:53:34 volumio volumio[7692]: spawnargs: [ May 04 15:53:34 volumio volumio[7692]: '-p', May 04 15:53:34 volumio volumio[7692]: 9876, May 04 15:53:34 volumio volumio[7692]: '-o', May 04 15:53:34 volumio volumio[7692]: '/tmp/camilladsp.log', May 04 15:53:34 volumio volumio[7692]: '-l', May 04 15:53:34 volumio volumio[7692]: 'warn', May 04 15:53:34 volumio volumio[7692]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 04 15:53:34 volumio volumio[7692]: ] May 04 15:53:34 volumio volumio[7692]: } May 04 15:53:34 volumio volumio[7692]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 15:53:34 volumio sudo[11882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 15:52 May 04 15:53:34 volumio sudo[11882]: 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="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"