Dec 22 11:33:05 volumio volumio[1528]: info: Sleep timer expired. Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioStop Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::stop Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::stPlaybackTimer Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::updateTrackBlock Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrackBlock Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::serviceStop Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::serviceStop Dec 22 11:33:05 volumio volumio[1528]: info: ControllerMpd::stop Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio volumio[1528]: info: Dec 22 11:33:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:33:05 volumio volumio[1528]: info: sendMpdCommand stop took 47 milliseconds Dec 22 11:33:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:33:05 volumio volumio[1528]: info: Dec 22 11:33:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:33:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:33:05 volumio volumio[1528]: info: sendMpdCommand status took 3 milliseconds Dec 22 11:33:05 volumio volumio[1528]: info: sendMpdCommand status took 1 milliseconds Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:33:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 22 11:33:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:33:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:33:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: 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":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:33:05 volumio volumio[1528]: verbose: CURRENT POSITION 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService stop Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio volumio[1528]: info: No code Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: 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":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:33:05 volumio volumio[1528]: verbose: CURRENT POSITION 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService stop Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio sudo[9671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio volumio[1528]: info: No code Dec 22 11:33:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:33:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:33:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:33:05 volumio volumio[1528]: info: ------------------------------ 70ms Dec 22 11:33:05 volumio volumio[1528]: info: ------------------------------ 70ms Dec 22 11:33:05 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio sudo[9681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio sudo[9683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio sudo[9688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:33:05 volumio sudo[9693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 22 11:33:05 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 22 11:33:05 volumio systemd[1]: peppymeterbasic.service: Consumed 49.332s CPU time. Dec 22 11:33:05 volumio sudo[9700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio sudo[9671]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio sudo[9681]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio sudo[9683]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio sudo[9696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:33:05 volumio sudo[9696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:33:05 volumio sudo[9693]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:33:05 volumio sudo[9688]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:33:05 volumio sudo[9700]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:33:05 volumio sudo[9696]: pam_unix(sudo:session): session closed for user root Dec 22 11:33:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:00 volumio volumio[1528]: info: CoreCommandRouter::volumioPlay Dec 22 11:34:00 volumio volumio[1528]: info: CoreStateMachine::play index undefined Dec 22 11:34:00 volumio volumio[1528]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:34:00 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:00 volumio volumio[1528]: info: CoreStateMachine::startPlaybackTimer Dec 22 11:34:00 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::clearAddPlayTracks USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:34:00 volumio volumio[1528]: info: sendMpdCommand stop took 1 milliseconds Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand clear Dec 22 11:34:00 volumio volumio[1528]: info: Dec 22 11:34:00 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:00 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:00 volumio volumio[1528]: info: sendMpdCommand clear took 1 milliseconds Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand add "USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac" Dec 22 11:34:00 volumio volumio[1528]: info: Dec 22 11:34:00 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:00 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:00 volumio volumio[1528]: error: updateQueue error: null Dec 22 11:34:00 volumio volumio[1528]: info: Dec 22 11:34:00 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:00 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:00 volumio volumio[1528]: info: ------------------------------ 2ms Dec 22 11:34:00 volumio volumio[1528]: info: sendMpdCommand add "USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac" took 2 milliseconds Dec 22 11:34:00 volumio volumio[1528]: info: ------------------------------ 1ms Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand play Dec 22 11:34:00 volumio volumio[1528]: info: Dec 22 11:34:00 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:00 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:00 volumio volumio[1528]: info: ------------------------------ 3ms Dec 22 11:34:00 volumio volumio[1528]: info: sendMpdCommand play took 2 milliseconds Dec 22 11:34:00 volumio volumio[1528]: info: ------------------------------ 2ms Dec 22 11:34:00 volumio volumio[1528]: info: Dec 22 11:34:00 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:00 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:00 volumio volumio[1528]: info: Dec 22 11:34:00 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:00 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:00 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:01 volumio volumio[1528]: info: Dec 22 11:34:01 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand status took 111 milliseconds Dec 22 11:34:01 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:01 volumio volumio[1528]: info: Dec 22 11:34:01 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:01 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand status took 113 milliseconds Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand status took 5 milliseconds Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:01 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2629 Kbps","isStreaming":false,"title":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:34:01 volumio volumio[1528]: verbose: CURRENT POSITION 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:34:01 volumio volumio[1528]: info: ------------------------------ 117ms Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand status took 8 milliseconds Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:01 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2741 Kbps","isStreaming":false,"title":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:34:01 volumio volumio[1528]: verbose: CURRENT POSITION 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:01 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:01 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":670,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2741 Kbps","isStreaming":false,"title":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:34:01 volumio volumio[1528]: verbose: CURRENT POSITION 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:01 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:01 volumio volumio[1528]: info: ------------------------------ 162ms Dec 22 11:34:01 volumio volumio[1528]: info: ------------------------------ 55ms Dec 22 11:34:01 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 22 11:34:01 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:01 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":675,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2673 Kbps","isStreaming":false,"title":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:34:01 volumio volumio[1528]: verbose: CURRENT POSITION 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:01 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:01 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:01 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:01 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:01 volumio volumio[1528]: info: ------------------------------ 90ms Dec 22 11:34:01 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:01 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:01 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:01 volumio sudo[9857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:01 volumio sudo[9857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:01 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:01 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:01 volumio sudo[9860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:01 volumio sudo[9860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:01 volumio sudo[9867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:01 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 22 11:34:01 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:01 volumio sudo[9867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:01 volumio sudo[9857]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:01 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:01 volumio sudo[9869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:01 volumio sudo[9869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:01 volumio sudo[9874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:01 volumio sudo[9874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:01 volumio sudo[9867]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:01 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:01 volumio sudo[9880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:01 volumio sudo[9880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:01 volumio sudo[9860]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:01 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:01 volumio sudo[9869]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:01 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:01 volumio sudo[9874]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:01 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:01 volumio sudo[9880]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:01 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioNext Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::next Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::stop Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::stPlaybackTimer Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::updateTrackBlock Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrackBlock Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::serviceStop Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 16 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::serviceStop Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::stop Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand stop took 23 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::play index undefined Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::startPlaybackTimer Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::clearAddPlayTracks USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::updateTrackBlock Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrackBlock Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand status took 5 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand stop took 2 milliseconds Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand clear Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:04 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand status took 1 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand clear took 1 milliseconds Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand add "USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac" Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: 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":"Пришёл пить воду","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/02 - Пришёл пить воду.flac","trackType":"flac"} Dec 22 11:34:04 volumio volumio[1528]: verbose: CURRENT POSITION 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState stateService stop Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: No code Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio sudo[9897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 63ms Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:04 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:04 volumio volumio[1528]: error: updateQueue error: null Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:04 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 57ms Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand add "USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac" took 56 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 1ms Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand play Dec 22 11:34:04 volumio volumio[1528]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 60ms Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:04 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:04 volumio sudo[9900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio sudo[9902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 47ms Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand play took 46 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 45ms Dec 22 11:34:04 volumio sudo[9902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio sudo[9905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 22 11:34:04 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 22 11:34:04 volumio systemd[1]: peppymeterbasic.service: Consumed 2.008s CPU time. Dec 22 11:34:04 volumio sudo[9900]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio sudo[9905]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio sudo[9897]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio sudo[9902]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand status took 115 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand status took 115 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:04 volumio volumio[1528]: info: Dec 22 11:34:04 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand status took 4 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand status took 4 milliseconds Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2301 Kbps","isStreaming":false,"title":"Губернатор","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac","trackType":"flac"} Dec 22 11:34:04 volumio volumio[1528]: verbose: CURRENT POSITION 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2301 Kbps","isStreaming":false,"title":"Губернатор","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac","trackType":"flac"} Dec 22 11:34:04 volumio volumio[1528]: verbose: CURRENT POSITION 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:04 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 141ms Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 147ms Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 22 11:34:04 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:04 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":218,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2356 Kbps","isStreaming":false,"title":"Губернатор","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac","trackType":"flac"} Dec 22 11:34:04 volumio volumio[1528]: verbose: CURRENT POSITION 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:04 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":218,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2356 Kbps","isStreaming":false,"title":"Губернатор","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac","trackType":"flac"} Dec 22 11:34:04 volumio volumio[1528]: verbose: CURRENT POSITION 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:04 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:04 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:04 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 91ms Dec 22 11:34:04 volumio volumio[1528]: info: ------------------------------ 84ms Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:04 volumio sudo[9911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:04 volumio sudo[9913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:04 volumio sudo[9916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 22 11:34:04 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:04 volumio sudo[9919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:04 volumio sudo[9911]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio sudo[9919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio sudo[9922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:04 volumio sudo[9922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:04 volumio sudo[9916]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio sudo[9913]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:04 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:04 volumio sudo[9919]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio sudo[9928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio sudo[9922]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio sudo[9928]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioNext Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::next Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::stop Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::stPlaybackTimer Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::updateTrackBlock Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrackBlock Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::serviceStop Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 17 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::serviceStop Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::stop Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand stop took 40 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::play index undefined Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::startPlaybackTimer Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::clearAddPlayTracks USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::updateTrackBlock Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrackBlock Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand status took 9 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand stop took 9 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand status took 3 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand clear Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:05 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand clear took 3 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand add "USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac" Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: 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":"Губернатор","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/03 - Губернатор.flac","trackType":"flac"} Dec 22 11:34:05 volumio volumio[1528]: verbose: CURRENT POSITION 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService stop Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: No code Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 22 11:34:05 volumio sudo[9932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 75ms Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 63ms Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:05 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:05 volumio volumio[1528]: error: updateQueue error: null Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:05 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 67ms Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand add "USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac" took 65 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand play Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces system playlist update Dec 22 11:34:05 volumio volumio[1528]: info: Ignoring MPD Status Update Dec 22 11:34:05 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 22 11:34:05 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 22 11:34:05 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 11:34:05 volumio sudo[9936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio sudo[9932]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 140ms Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 133ms Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand play took 130 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 129ms Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:05 volumio sudo[9938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand status took 44 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand status took 36 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:05 volumio sudo[9942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:05 volumio volumio[1528]: info: Dec 22 11:34:05 volumio volumio[1528]: ---------------------------- MPD announces state update: player Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::getState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand status took 24 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2556 Kbps","isStreaming":false,"title":"Не было такой","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac","trackType":"flac"} Dec 22 11:34:05 volumio volumio[1528]: verbose: CURRENT POSITION 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":214,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2422 Kbps","isStreaming":false,"title":"Не было такой","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac","trackType":"flac"} Dec 22 11:34:05 volumio volumio[1528]: verbose: CURRENT POSITION 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:05 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 86ms Dec 22 11:34:05 volumio sudo[9936]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 98ms Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand status took 44 milliseconds Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseState Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2530 Kbps","isStreaming":false,"title":"Не было такой","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac","trackType":"flac"} Dec 22 11:34:05 volumio volumio[1528]: verbose: CURRENT POSITION 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:05 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio sudo[9942]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio sudo[9938]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 118ms Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:05 volumio sudo[9947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio sudo[9949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:05 volumio volumio[1528]: info: sendMpdCommand playlistinfo took 151 milliseconds Dec 22 11:34:05 volumio volumio[1528]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:34:05 volumio volumio[1528]: info: ControllerMpd::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::servicePushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2530 Kbps","isStreaming":false,"title":"Не было такой","artist":"Борис Гребенщиков","album":"СОЛЬ","uri":"USB/6E4E-A23F/Music/!Russian/БГ/Борис Гребенщиков - 2014 - СОЛЬ [2014 qobaz] [FLAC 24-96]/04 - Не было такой.flac","trackType":"flac"} Dec 22 11:34:05 volumio volumio[1528]: verbose: CURRENT POSITION 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState stateService play Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:34:05 volumio volumio[1528]: info: Received an update from plugin. extracting info from payload Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio volumio[1528]: info: CoreStateMachine::pushState Dec 22 11:34:05 volumio volumio[1528]: info: CorePlayQueue::getTrack 18 Dec 22 11:34:05 volumio volumio[1528]: info: CoreCommandRouter::volumioPushState Dec 22 11:34:05 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 22 11:34:05 volumio sudo[9949]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio sudo[9953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio sudo[9947]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio sudo[9955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio volumio[1528]: info: ------------------------------ 252ms Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Stop Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:05 volumio sudo[9953]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio sudo[9955]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio sudo[9963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio sudo[9964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 22 11:34:05 volumio sudo[9964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:34:05 volumio sudo[9963]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:05 volumio sudo[9964]: pam_unix(sudo:session): session closed for user root Dec 22 11:34:05 volumio volumio[1528]: info: peppymeterbasic Daemon Started Dec 22 11:34:16 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 11:34:16 volumio volumio[1528]: info: CURURI: music-library Dec 22 11:34:16 volumio volumio[1528]: info: Preload queue cleared Dec 22 11:34:18 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 11:34:18 volumio volumio[1528]: info: CURURI: music-library/USB Dec 22 11:34:18 volumio volumio[1528]: info: Preload queue cleared Dec 22 11:34:18 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 11:34:18 volumio volumio[1528]: info: CURURI: music-library/USB/6E4E-A23F Dec 22 11:34:18 volumio volumio[1528]: info: Preload queue cleared Dec 22 11:34:19 volumio volumio[1528]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 11:34:19 volumio volumio[1528]: info: CURURI: music-library/USB/6E4E-A23F/Music Dec 22 11:34:19 volumio volumio[1528]: info: Preload queue cleared Dec 22 11:34:28 volumio volumio[1528]: info: CoreCommandRouter::volumioAddQueueItems Dec 22 11:34:28 volumio volumio[1528]: info: CoreStateMachine::addQueueItems Dec 22 11:34:28 volumio volumio[1528]: info: CorePlayQueue::addQueueItems Dec 22 11:34:28 volumio volumio[1528]: info: Preload queue cleared Dec 22 11:34:28 volumio volumio[1528]: info: Adding Item to queue: music-library/USB/6E4E-A23F/Music/Anathema Dec 22 11:34:28 volumio volumio[1528]: info: Exploding uri music-library/USB/6E4E-A23F/Music/Anathema in service mpd Dec 22 11:34:28 volumio volumio[1528]: info: ALBUMART /albumart?cacheid=219&web=Anathema/Weather%20Systems/extralarge&path=%2Fmnt%2FUSB%2F6E4E-A23F%2FMusic%2FAnathema%2F2012%20-%20Weather%20Systems%2FAnathema%20-%20Weather%20Systems.cue&metadata=false Dec 22 11:34:28 volumio volumio[1528]: info: URI /mnt/USB/6E4E-A23F/Music/Anathema/2012 - Weather Systems/Anathema - Weather Systems.cue Dec 22 11:34:28 volumio volumio[1528]: info: ALBUMART /albumart?cacheid=219&web=Anathema/Weather%20Systems/extralarge&path=%2Fmnt%2FUSB%2F6E4E-A23F%2FMusic%2FAnathema%2F2012%20-%20Weather%20Systems%2FAnathema%20-%20Weather%20Systems.cue&metadata=false Dec 22 11:34:28 volumio volumio[1528]: info: URI /mnt/USB/6E4E-A23F/Music/Anathema/2012 - Weather Systems/Anathema - Weather Systems.cue Dec 22 11:34:28 volumio volumio[1528]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 11:34:28 volumio volumio[1528]: Error: Unable to resolve or reject the same promise twice Dec 22 11:34:28 volumio volumio[1528]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 22 11:34:28 volumio volumio[1528]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 22 11:34:28 volumio volumio[1528]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 22 11:34:28 volumio volumio[1528]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 22 11:34:28 volumio volumio[1528]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 22 11:34:28 volumio volumio[1528]: at Socket.emit (node:events:514:28) Dec 22 11:34:28 volumio volumio[1528]: at addChunk (node:internal/streams/readable:343:12) Dec 22 11:34:28 volumio volumio[1528]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 22 11:34:28 volumio volumio[1528]: at Readable.push (node:internal/streams/readable:253:10) Dec 22 11:34:28 volumio volumio[1528]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 22 11:34:28 volumio volumio[1528]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 11:34:28 volumio sudo[10061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 11:33' Dec 22 11:34:28 volumio sudo[10061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"