-- Logs begin at Thu 2025-09-11 02:02:25 CEST, end at Thu 2025-09-11 02:32:58 CEST. -- Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioPlay Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::play index 6 Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::stop Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 5 Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:02 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:02 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 5 Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::serviceStop Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 5 Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Sep 11 02:31:02 volumio volumio[1185]: info: [1757550662592] ControllerQobuz::stop Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 02:31:02 volumio volumio[1185]: info: ControllerMpd::stop Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 02:31:02 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:31:02 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:31:02 volumio sudo[10839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:31:02 volumio sudo[10839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:02 volumio volumio[1185]: info: Sep 11 02:31:02 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:31:02 volumio volumio[1185]: info: sendMpdCommand stop took 21 milliseconds Sep 11 02:31:02 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::play index undefined Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::startPlaybackTimer Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: [1757550662613] ControllerQobuz::clearAddPlayTrack Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 02:31:02 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:31:02 volumio volumio[1185]: info: sendMpdCommand stop took 0 milliseconds Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 02:31:02 volumio volumio[1185]: info: Sep 11 02:31:02 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:31:02 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:31:02 volumio systemd[1]: Stopping peppymeterbasic Daemon... Sep 11 02:31:02 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Sep 11 02:31:02 volumio sudo[10842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:31:02 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:31:02 volumio sudo[10842]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:02 volumio volumio[1185]: info: Sep 11 02:31:02 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:31:02 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:31:02 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 11 02:31:02 volumio volumio[1185]: info: sendMpdCommand clear took 5 milliseconds Sep 11 02:31:02 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/197320140" Sep 11 02:31:02 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:31:02 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:02 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:02 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Sep 11 02:31:02 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:31:02 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:02 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:02 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:02 volumio volumio[1185]: STREAMING PROXY: Handling url /?data=qobuz://song/197320140 Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:02 volumio volumio[1185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Sep 11 02:31:02 volumio volumio[1185]: info: ------------------------------ 13ms Sep 11 02:31:02 volumio volumio[1185]: info: ------------------------------ 10ms Sep 11 02:31:02 volumio volumio[1185]: info: Sep 11 02:31:02 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:31:02 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:31:02 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:31:02 volumio volumio[1185]: error: updateQueue error: null Sep 11 02:31:02 volumio volumio[1185]: info: ------------------------------ 9ms Sep 11 02:31:02 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:31:02 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:31:02 volumio sudo[10845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:31:02 volumio sudo[10845]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:02 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:31:02 volumio sudo[10848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:31:02 volumio systemd[1]: peppymeterbasic.service: Succeeded. Sep 11 02:31:02 volumio systemd[1]: Stopped peppymeterbasic Daemon. Sep 11 02:31:02 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:31:02 volumio sudo[10845]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:02 volumio sudo[10839]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:02 volumio sudo[10848]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:02 volumio sudo[10842]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:02 volumio sudo[10850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:31:02 volumio sudo[10850]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:02 volumio sudo[10854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:31:02 volumio sudo[10854]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:02 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:31:02 volumio sudo[10850]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:02 volumio sudo[10848]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:02 volumio sudo[10854]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:02 volumio volumio[1185]: info: Executing endpoint getStreamUrlqobuz Sep 11 02:31:02 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 11 02:31:02 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:31:02 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:31:02 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:31:02 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:31:02 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:31:02 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:31:02 volumio volumio[1185]: info: getStreamUrl took 236 milliseconds Sep 11 02:31:02 volumio volumio[1185]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193257&eid=197320140&fmt=7&profile=raw&app_id=539451548&cid=2365247&etsp=1757554262&hmac=YL12Fbf4caBTG1bfatwsHt5owgk Sep 11 02:31:03 volumio volumio[1185]: STREAMING PROXY: Response: 200, length: 57337361 Sep 11 02:31:03 volumio volumio[1185]: STREAMING PROXY: Client dropped request, destroying Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/197320140" Sep 11 02:31:03 volumio volumio[1185]: error: updateQueue error: null Sep 11 02:31:03 volumio volumio[1185]: info: ------------------------------ 943ms Sep 11 02:31:03 volumio volumio[1185]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/197320140" took 1 milliseconds Sep 11 02:31:03 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Sep 11 02:31:03 volumio volumio[1185]: info: Sep 11 02:31:03 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:31:03 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:31:03 volumio volumio[1185]: info: Sep 11 02:31:03 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:31:03 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:31:03 volumio volumio[1185]: info: sendMpdCommand play took 1 milliseconds Sep 11 02:31:03 volumio volumio[1185]: info: ------------------------------ 1ms Sep 11 02:31:03 volumio volumio[1185]: info: ------------------------------ 0ms Sep 11 02:31:03 volumio volumio[1185]: STREAMING PROXY: Handling url /?data=qobuz://song/197320140 Sep 11 02:31:03 volumio volumio[1185]: info: Executing endpoint getStreamUrlqobuz Sep 11 02:31:03 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 11 02:31:03 volumio volumio[1185]: info: getStreamUrl took 244 milliseconds Sep 11 02:31:03 volumio volumio[1185]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193257&eid=197320140&fmt=7&profile=raw&app_id=539451548&cid=2365247&etsp=1757554263&hmac=Q7-06Bflcl9tqw1F9lcZ23M08ko Sep 11 02:31:03 volumio volumio[1185]: STREAMING PROXY: Response: 200, length: 57337361 Sep 11 02:31:03 volumio volumio[1185]: info: Sep 11 02:31:03 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:31:03 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:31:03 volumio volumio[1185]: info: Sep 11 02:31:03 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:31:03 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:31:03 volumio volumio[1185]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Sep 11 02:31:03 volumio volumio[1185]: info: FusionDsp - ---- read samplerate from file: 48000 Sep 11 02:31:03 volumio volumio[1185]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 11 02:31:03 volumio volumio[1185]: info: Sep 11 02:31:03 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:31:03 volumio volumio[1185]: info: sendMpdCommand status took 8 milliseconds Sep 11 02:31:03 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:31:03 volumio volumio[1185]: info: sendMpdCommand status took 8 milliseconds Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:31:03 volumio volumio[1185]: info: Sep 11 02:31:03 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:31:03 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:31:03 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:31:04 volumio volumio[1185]: info: sendMpdCommand status took 2 milliseconds Sep 11 02:31:04 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 02:31:04 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 02:31:04 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:31:04 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:31:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:04 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"197320140","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/197320140","trackType":"qobuz"} Sep 11 02:31:04 volumio volumio[1185]: verbose: CURRENT POSITION 6 Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Sep 11 02:31:04 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:31:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:04 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"197320140","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/197320140","trackType":"qobuz"} Sep 11 02:31:04 volumio volumio[1185]: verbose: CURRENT POSITION 6 Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:31:04 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:04 volumio volumio[1185]: info: ------------------------------ 14ms Sep 11 02:31:04 volumio volumio[1185]: info: ------------------------------ 14ms Sep 11 02:31:04 volumio volumio[1185]: error: FusionDsp - WebSocket error: [object Object] Sep 11 02:31:04 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 11 02:31:04 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:31:04 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:31:04 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:31:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:04 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"197320140","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/197320140","trackType":"qobuz"} Sep 11 02:31:04 volumio volumio[1185]: verbose: CURRENT POSITION 6 Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:31:04 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:04 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:31:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:31:04 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"197320140","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/197320140","trackType":"qobuz"} Sep 11 02:31:04 volumio volumio[1185]: verbose: CURRENT POSITION 6 Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:31:04 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:31:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:31:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:04 volumio volumio[1185]: info: ------------------------------ 13ms Sep 11 02:31:04 volumio volumio[1185]: info: ------------------------------ 12ms Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10860]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10863]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10867]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio systemd[1]: Started peppymeterbasic Daemon. Sep 11 02:31:04 volumio sudo[10860]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio sudo[10863]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio sudo[10867]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10871]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10871]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio sudo[10874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10874]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio sudo[10874]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10877]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio sudo[10880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10880]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio sudo[10880]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10883]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10883]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio sudo[10886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio sudo[10886]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10889]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio sudo[10889]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:31:04 volumio sudo[10892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio sudo[10892]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio sudo[10892]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio sudo[10895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:31:04 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 11 02:31:04 volumio sudo[10895]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio sudo[10895]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:04 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:31:04 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:04 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:05 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:05 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:05 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:05 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:05 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:07 volumio volumio[1185]: STREAMING PROXY: Client dropped request, destroying Sep 11 02:31:10 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Sep 11 02:31:11 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Sep 11 02:31:20 volumio volumio[1185]: 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: 11 Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:31:20 volumio volumio[1185]: 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: 11 Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 02:31:20 volumio volumio[1185]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 11 02:31:20 volumio volumio[1185]: info: Listing playlists Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 02:31:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:31:22 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 02:31:22 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:22 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:22 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:22 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:22 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:22 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::volumioGetQueue Sep 11 02:31:23 volumio volumio[1185]: info: CoreStateMachine::getQueue Sep 11 02:31:23 volumio volumio[1185]: info: CorePlayQueue::getQueue Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 02:31:23 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:23 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:23 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:24 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:24 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:24 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 11 02:31:34 volumio volumio[1185]: info: Received Get System Version Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 02:31:34 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:31:38 volumio volumio[1185]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 11 02:31:38 volumio volumio[1185]: info: Received Get System Version Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 02:31:38 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:38 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:38 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:41 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:41 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:41 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:41 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:41 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:44 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:44 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:44 volumio volumio[10864]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:31:46 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Sep 11 02:31:47 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Sep 11 02:31:48 volumio volumio[1185]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Sep 11 02:31:48 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Sep 11 02:31:48 volumio volumio[1185]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Sep 11 02:31:48 volumio sudo[11039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/active_volumio_ui Sep 11 02:31:48 volumio sudo[11039]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:48 volumio sudo[11039]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:49 volumio volumio[1185]: info: CoreCommandRouter::Reload Ui Sep 11 02:31:50 volumio volumio[1185]: 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: 10 Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 02:31:50 volumio volumio[1185]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:31:50 volumio volumio[1185]: info: Received Get System Version Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 02:31:50 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 02:31:52 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:52 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 02:31:52 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:52 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:52 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:53 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:31:53 volumio volumio[1185]: info: CoreCommandRouter::volumioGetQueue Sep 11 02:31:53 volumio volumio[1185]: info: CoreStateMachine::getQueue Sep 11 02:31:53 volumio volumio[1185]: info: CorePlayQueue::getQueue Sep 11 02:31:53 volumio volumio[1185]: info: Listing playlists Sep 11 02:31:54 volumio sudo[11067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 02:31:54 volumio sudo[11067]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:54 volumio sudo[11067]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:54 volumio sudo[11070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 02:31:54 volumio sudo[11070]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:31:54 volumio sudo[11070]: pam_unix(sudo:session): session closed for user root Sep 11 02:31:54 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129 from 192.168.8.138 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 02:31:55 volumio volumio[1185]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 02:31:55 volumio volumio[1185]: info: Listing playlists Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 02:31:55 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:31:56 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 02:31:56 volumio volumio[1185]: info: CURURI: playlists Sep 11 02:31:56 volumio volumio[1185]: info: Listing playlists Sep 11 02:31:56 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 02:31:58 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:58 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 02:31:58 volumio volumio[1185]: info: Received Get System Info Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:31:58 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:31:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 02:31:59 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:00 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 11 02:32:00 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:02 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 11 02:32:02 volumio volumio[1185]: info: Getting webradio selection Sep 11 02:32:02 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:04 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::ClearQueue Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::stop Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::serviceStop Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 6 Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Sep 11 02:32:04 volumio volumio[1185]: info: [1757550724368] ControllerQobuz::stop Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 02:32:04 volumio volumio[1185]: info: ControllerMpd::stop Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::clearPlayQueue Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::saveQueue Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::addQueueItems Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::addQueueItems Sep 11 02:32:04 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:04 volumio volumio[1185]: info: Adding Item to queue: http://stream.artsound.fm/mp3 Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::saveQueue Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPlay Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::play index 0 Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::stop Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::play index undefined Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::startPlaybackTimer Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 11 02:32:04 volumio volumio[1185]: info: [1757550724373] ControllerWebradio::clearAddPlayTrack Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 02:32:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:04 volumio sudo[11088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:04 volumio sudo[11088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:04 volumio systemd[1]: Stopping peppymeterbasic Daemon... Sep 11 02:32:04 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Sep 11 02:32:04 volumio volumio[1185]: info: Sep 11 02:32:04 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:04 volumio volumio[1185]: info: sendMpdCommand stop took 28 milliseconds Sep 11 02:32:04 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:04 volumio volumio[1185]: info: sendMpdCommand stop took 23 milliseconds Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 02:32:04 volumio volumio[1185]: info: Sep 11 02:32:04 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:04 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:04 volumio volumio[1185]: info: Sep 11 02:32:04 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:04 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:04 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:32:04 volumio volumio[1185]: info: sendMpdCommand clear took 1 milliseconds Sep 11 02:32:04 volumio volumio[1185]: info: sendMpdCommand status took 0 milliseconds Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand load "http://stream.artsound.fm/mp3" Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:04 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:04 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:04 volumio sudo[11091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:04 volumio sudo[11091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:04 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:04 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:04 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:04 volumio volumio[1185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 11 02:32:04 volumio volumio[1185]: info: ------------------------------ 6ms Sep 11 02:32:04 volumio volumio[1185]: info: Sep 11 02:32:04 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:04 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:04 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:04 volumio volumio[1185]: error: updateQueue error: null Sep 11 02:32:04 volumio volumio[1185]: info: ------------------------------ 7ms Sep 11 02:32:04 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 11 02:32:04 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:04 volumio volumio[1185]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 11 02:32:04 volumio volumio[1185]: info: ------------------------------ 9ms Sep 11 02:32:04 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:04 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:04 volumio sudo[11095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:04 volumio sudo[11095]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:04 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:04 volumio sudo[11098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:04 volumio sudo[11098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:04 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:04 volumio systemd[1]: peppymeterbasic.service: Succeeded. Sep 11 02:32:04 volumio systemd[1]: Stopped peppymeterbasic Daemon. Sep 11 02:32:04 volumio sudo[11095]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:04 volumio sudo[11091]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:04 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:04 volumio sudo[11088]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:04 volumio sudo[11098]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:04 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:04 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:04 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:05 volumio volumio[1185]: error: updateQueue error: null Sep 11 02:32:05 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand add "http://stream.artsound.fm/mp3" Sep 11 02:32:05 volumio volumio[1185]: info: ------------------------------ 1316ms Sep 11 02:32:05 volumio volumio[1185]: info: Sep 11 02:32:05 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:05 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:05 volumio volumio[1185]: info: sendMpdCommand add "http://stream.artsound.fm/mp3" took 0 milliseconds Sep 11 02:32:05 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 02:32:05 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Sep 11 02:32:05 volumio volumio[1185]: info: ------------------------------ 0ms Sep 11 02:32:05 volumio volumio[1185]: info: Sep 11 02:32:05 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:05 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:05 volumio volumio[1185]: info: sendMpdCommand play took 1 milliseconds Sep 11 02:32:05 volumio volumio[1185]: info: ------------------------------ 1ms Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 11 02:32:06 volumio volumio[1185]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 11 02:32:06 volumio volumio[1185]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 6 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 6 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 0 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 7ms Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 14ms Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 8ms Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 8 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11102]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11105]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio systemd[1]: Started peppymeterbasic Daemon. Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11109]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11102]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 62 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio sudo[11109]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11115]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11112]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11105]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio sudo[11118]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11121]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11125]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 79ms Sep 11 02:32:06 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 11 02:32:06 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 11 02:32:06 volumio sudo[11125]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11128]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11131]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11134]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio sudo[11134]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio sudo[11137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11137]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:06 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:06 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:06 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 2ms Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 1ms Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:06 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:06 volumio volumio[1185]: info: Sep 11 02:32:06 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 1ms Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 1ms Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SENIOR MEMORIES","artist":"ArtSound FM92.7","album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1089,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SENIOR MEMORIES","artist":"ArtSound FM92.7","album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 8ms Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 8ms Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand status took 8 milliseconds Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SENIOR MEMORIES","artist":"ArtSound FM92.7","album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 12ms Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11143]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11146]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11146]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11149]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11149]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11152]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11152]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11155]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11155]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11158]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11158]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11161]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11161]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11164]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11167]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11167]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11170]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11170]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11173]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 103 milliseconds Sep 11 02:32:06 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:06 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:06 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:06 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SENIOR MEMORIES","artist":"ArtSound FM92.7","album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:06 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:06 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio sudo[11173]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:06 volumio sudo[11176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:06 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:06 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:06 volumio sudo[11176]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: ------------------------------ 118ms Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11179]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11179]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio sudo[11182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:06 volumio sudo[11185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11185]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11182]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio sudo[11185]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:06 volumio sudo[11188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:06 volumio sudo[11188]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:06 volumio sudo[11188]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:06 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:32:08 volumio volumio[1185]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 11 02:32:08 volumio volumio[1185]: info: Received Get System Version Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 02:32:08 volumio volumio[1185]: info: Received Get System Info Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:32:08 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 02:32:13 volumio volumio[1185]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 11 02:32:13 volumio volumio[1185]: info: Received Get System Version Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 02:32:13 volumio volumio[1185]: info: Received Get System Info Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:32:13 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:13 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:32:15 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 11 02:32:15 volumio volumio[1185]: info: handleBrowseUri took 150 milliseconds Sep 11 02:32:15 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:15 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:17 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 11 02:32:17 volumio volumio[1185]: info: handleBrowseUri took 430 milliseconds Sep 11 02:32:17 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:17 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:32:17 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:32:17 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:32:17 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:17 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:32:18 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Sep 11 02:32:18 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Sep 11 02:32:19 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::ClearQueue Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::stop Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::serviceStop Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Sep 11 02:32:19 volumio volumio[1185]: info: [1757550739320] ControllerWebradio::stop Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::clearPlayQueue Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::saveQueue Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::addQueueItems Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::addQueueItems Sep 11 02:32:19 volumio volumio[1185]: info: Preload queue cleared Sep 11 02:32:19 volumio volumio[1185]: info: Adding Item to queue: qobuz://album/0060256725631 Sep 11 02:32:19 volumio volumio[1185]: info: Exploding uri qobuz://album/0060256725631 in service qobuz Sep 11 02:32:19 volumio volumio[1185]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11208]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio systemd[1]: Stopping peppymeterbasic Daemon... Sep 11 02:32:19 volumio volumio[1185]: info: Sep 11 02:32:19 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:19 volumio volumio[1185]: info: sendMpdCommand stop took 26 milliseconds Sep 11 02:32:19 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:19 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Sep 11 02:32:19 volumio volumio[1185]: info: Sep 11 02:32:19 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:19 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:19 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:19 volumio sudo[11211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio volumio[1185]: info: sendMpdCommand status took 3 milliseconds Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:19 volumio sudo[11211]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:19 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: 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":"SENIOR MEMORIES","artist":"ArtSound FM92.7","album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:19 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::syncState stateService stop Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: No code Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: 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":"SENIOR MEMORIES","artist":"ArtSound FM92.7","album":null,"uri":"http://stream.artsound.fm/mp3","trackType":"fm/mp3"} Sep 11 02:32:19 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::syncState stateService stop Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: No code Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:19 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: ------------------------------ 14ms Sep 11 02:32:19 volumio volumio[1185]: info: ------------------------------ 15ms Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11214]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11217]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio systemd[1]: peppymeterbasic.service: Succeeded. Sep 11 02:32:19 volumio systemd[1]: Stopped peppymeterbasic Daemon. Sep 11 02:32:19 volumio sudo[11220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11220]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio sudo[11214]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11208]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11211]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11217]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11220]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11223]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11225]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio sudo[11223]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11225]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11229]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11229]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11232]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11232]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11235]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11235]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11238]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11238]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11241]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 11 02:32:19 volumio sudo[11241]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio sudo[11244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11244]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:19 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 02:32:19 volumio sudo[11244]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio volumio[1185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 11 02:32:19 volumio sudo[11247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:19 volumio sudo[11247]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:19 volumio sudo[11247]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:19 volumio volumio[1185]: info: explodeUri took 462 milliseconds Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::saveQueue Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::volumioPlay Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::play index 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::stop Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::play index undefined Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: CoreStateMachine::startPlaybackTimer Sep 11 02:32:19 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:19 volumio volumio[1185]: info: [1757550739785] ControllerQobuz::clearAddPlayTrack Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 02:32:19 volumio volumio[1185]: info: sendMpdCommand stop took 1 milliseconds Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 02:32:19 volumio volumio[1185]: info: Sep 11 02:32:19 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:19 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:19 volumio volumio[1185]: info: sendMpdCommand clear took 0 milliseconds Sep 11 02:32:19 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/45716466" Sep 11 02:32:19 volumio volumio[1185]: info: Sep 11 02:32:19 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:19 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:19 volumio volumio[1185]: STREAMING PROXY: Handling url /?data=qobuz://song/45716466 Sep 11 02:32:19 volumio volumio[1185]: info: Executing endpoint getStreamUrlqobuz Sep 11 02:32:19 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 11 02:32:20 volumio volumio[1185]: info: getStreamUrl took 278 milliseconds Sep 11 02:32:20 volumio volumio[1185]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193257&eid=45716466&fmt=7&profile=raw&app_id=539451548&cid=2365247&etsp=1757554339&hmac=8a27BQ_WTOAqR5SzaM42OfDqUN4 Sep 11 02:32:20 volumio volumio[1185]: STREAMING PROXY: Response: 200, length: 52681274 Sep 11 02:32:20 volumio volumio[1185]: STREAMING PROXY: Client dropped request, destroying Sep 11 02:32:20 volumio volumio[1185]: error: updateQueue error: null Sep 11 02:32:20 volumio volumio[1185]: error: updateQueue error: null Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 668ms Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/45716466" Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 668ms Sep 11 02:32:20 volumio volumio[1185]: info: Sep 11 02:32:20 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:20 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/45716466" took 0 milliseconds Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Sep 11 02:32:20 volumio volumio[1185]: info: Sep 11 02:32:20 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Sep 11 02:32:20 volumio volumio[1185]: info: Ignoring MPD Status Update Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 0ms Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand play took 0 milliseconds Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 0ms Sep 11 02:32:20 volumio volumio[1185]: STREAMING PROXY: Handling url /?data=qobuz://song/45716466 Sep 11 02:32:20 volumio volumio[1185]: info: Executing endpoint getStreamUrlqobuz Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:32:20 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:32:20 volumio volumio[1185]: info: getStreamUrl took 289 milliseconds Sep 11 02:32:20 volumio volumio[1185]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193257&eid=45716466&fmt=7&profile=raw&app_id=539451548&cid=2365247&etsp=1757554340&hmac=o8OWnLcnpY0Y67IB_I-Af-MVcDs Sep 11 02:32:20 volumio volumio[1185]: STREAMING PROXY: Response: 200, length: 52681274 Sep 11 02:32:20 volumio volumio[1185]: info: Sep 11 02:32:20 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:20 volumio volumio[1185]: info: Sep 11 02:32:20 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:20 volumio volumio[1185]: info: FusionDsp - ---- read samplerate, raw: 88200,S32_LE,2,32 Sep 11 02:32:20 volumio volumio[1185]: info: FusionDsp - ---- read samplerate from file: 88200 Sep 11 02:32:20 volumio volumio[1185]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Sep 11 02:32:20 volumio volumio[1185]: info: Sep 11 02:32:20 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:20 volumio volumio[1185]: info: Sep 11 02:32:20 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand status took 0 milliseconds Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:20 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:20 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"45716466","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45716466","trackType":"qobuz"} Sep 11 02:32:20 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:20 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:20 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"45716466","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45716466","trackType":"qobuz"} Sep 11 02:32:20 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:20 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 6ms Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 7ms Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand status took 3 milliseconds Sep 11 02:32:20 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:20 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:20 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:20 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:20 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"45716466","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45716466","trackType":"qobuz"} Sep 11 02:32:20 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:20 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:20 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:20 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:20 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:20 volumio volumio[1185]: info: ------------------------------ 7ms Sep 11 02:32:20 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:20 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:20 volumio sudo[11267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11267]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:20 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:20 volumio sudo[11270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11270]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:20 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:20 volumio systemd[1]: Started peppymeterbasic Daemon. Sep 11 02:32:20 volumio sudo[11274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11274]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:20 volumio sudo[11277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11277]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:20 volumio sudo[11280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:20 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:20 volumio sudo[11286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11286]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:20 volumio sudo[11274]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:20 volumio sudo[11277]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:20 volumio sudo[11267]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:20 volumio sudo[11280]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:20 volumio sudo[11270]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:20 volumio sudo[11283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:20 volumio sudo[11283]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:21 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 82 milliseconds Sep 11 02:32:21 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:21 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:21 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:21 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:21 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"45716466","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45716466","trackType":"qobuz"} Sep 11 02:32:21 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:21 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Sep 11 02:32:21 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Sep 11 02:32:21 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Sep 11 02:32:21 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:21 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:21 volumio sudo[11286]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio sudo[11283]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio sudo[11290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:21 volumio sudo[11290]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:21 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:21 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:21 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:21 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:21 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:21 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:21 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:21 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:21 volumio volumio[1185]: info: ------------------------------ 99ms Sep 11 02:32:21 volumio sudo[11290]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:21 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:21 volumio sudo[11293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:21 volumio sudo[11293]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:21 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:21 volumio sudo[11293]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio sudo[11296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:21 volumio sudo[11296]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:21 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 11 02:32:21 volumio sudo[11296]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio sudo[11299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:21 volumio sudo[11299]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:21 volumio sudo[11299]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio sudo[11302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 11 02:32:21 volumio sudo[11302]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:21 volumio sudo[11302]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:21 volumio volumio[1185]: info: peppymeterbasic Daemon Started Sep 11 02:32:21 volumio volumio[11271]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:32:21 volumio volumio[11271]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:32:24 volumio volumio[1185]: STREAMING PROXY: Client dropped request, destroying Sep 11 02:32:41 volumio volumio[11271]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:32:41 volumio volumio[11271]: libpng warning: iCCP: known incorrect sRGB profile Sep 11 02:32:48 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 02:32:48 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 02:32:48 volumio volumio[1185]: info: Discovery: Getting this device information Sep 11 02:32:48 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:48 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 02:32:49 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Sep 11 02:32:49 volumio systemd[1]: peppymeterbasic.service: Succeeded. Sep 11 02:32:49 volumio volumio[1185]: verbose: New Socket.io Connection to 192.168.8.129:3000 from 192.168.8.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 11 02:32:56 volumio volumio[1185]: info: Pausing Remote Device: http://192.168.8.129 Sep 11 02:32:56 volumio volumio[1185]: info: Sep 11 02:32:56 volumio volumio[1185]: ---------------------------- Client requests Volumio pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::volumioPause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::servicePause Sep 11 02:32:56 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::servicePause Sep 11 02:32:56 volumio volumio[1185]: info: [1757550776912] ControllerQobuz::pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 02:32:56 volumio volumio[1185]: info: ControllerMpd::pause Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand pause Sep 11 02:32:56 volumio volumio[1185]: info: Sep 11 02:32:56 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:56 volumio volumio[1185]: info: sendMpdCommand pause took 2 milliseconds Sep 11 02:32:56 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:56 volumio volumio[1185]: info: Sep 11 02:32:56 volumio volumio[1185]: ---------------------------- MPD announces state update: player Sep 11 02:32:56 volumio volumio[1185]: info: ControllerMpd::getState Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Sep 11 02:32:56 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:56 volumio volumio[1185]: info: Done pausing: Sep 11 02:32:56 volumio volumio[1185]: info: sendMpdCommand status took 2 milliseconds Sep 11 02:32:56 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::parseState Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:56 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:56 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:56 volumio volumio[1185]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1616,"duration":174,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1415 Kbps","isStreaming":false,"title":"45716466","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45716466","trackType":"qobuz"} Sep 11 02:32:56 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::syncState stateService pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:56 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:56 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Sep 11 02:32:56 volumio volumio[1185]: info: ------------------------------ 5ms Sep 11 02:32:56 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 11 02:32:56 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Sep 11 02:32:56 volumio volumio[1185]: info: ControllerMpd::pushState Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Sep 11 02:32:56 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Sep 11 02:32:56 volumio volumio[1185]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1616,"duration":174,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1415 Kbps","isStreaming":false,"title":"45716466","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45716466","trackType":"qobuz"} Sep 11 02:32:56 volumio volumio[1185]: verbose: CURRENT POSITION 0 Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::syncState stateService pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus pause Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::pushState Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Sep 11 02:32:56 volumio volumio[1185]: info: MRS: Pushing multiroomSync output update for this device Sep 11 02:32:56 volumio volumio[1185]: info: MRS: Pushing multiroomSync output Sep 11 02:32:56 volumio volumio[1185]: info: CoreCommandRouter::volumioGetState Sep 11 02:32:56 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Sep 11 02:32:56 volumio volumio[1185]: info: ------------------------------ 7ms Sep 11 02:32:56 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status pause Sep 11 02:32:56 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status pause Sep 11 02:32:56 volumio sudo[11437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:56 volumio sudo[11437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:56 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status pause Sep 11 02:32:56 volumio sudo[11440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:56 volumio sudo[11440]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:56 volumio sudo[11437]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:56 volumio volumio[1185]: info: PeppyMeterBasic ---peppymeterbasic status pause Sep 11 02:32:56 volumio sudo[11440]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:56 volumio sudo[11443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:56 volumio sudo[11443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:56 volumio sudo[11443]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:56 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:56 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:56 volumio sudo[11446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 11 02:32:56 volumio sudo[11446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 02:32:56 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:56 volumio sudo[11446]: pam_unix(sudo:session): session closed for user root Sep 11 02:32:56 volumio volumio[1185]: info: peppymeterbasic Daemon Stop Sep 11 02:32:58 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Sep 11 02:32:58 volumio volumio[1185]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Sep 11 02:32:58 volumio volumio[1185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 02:32:58 volumio volumio[1185]: TypeError: Cannot read property 'then' of undefined Sep 11 02:32:58 volumio volumio[1185]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Sep 11 02:32:58 volumio volumio[1185]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Sep 11 02:32:58 volumio volumio[1185]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Sep 11 02:32:58 volumio volumio[1185]: at Socket.emit (events.js:400:28) Sep 11 02:32:58 volumio volumio[1185]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 11 02:32:58 volumio volumio[1185]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 11 02:32:58 volumio volumio[1185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 02:32:58 volumio sudo[11457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 02:31 Sep 11 02:32:58 volumio sudo[11457]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"