-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-09-03 12:37:39 CEST. -- Sep 03 12:36:15 rivoplus volumio[3190]: verbose: New Socket.io Connection to 192.168.178.104 from 192.168.178.157 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Sep 03 12:36:15 rivoplus volumio[3190]: info: Received Get System Info Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:36:15 rivoplus volumio[3190]: info: Discovery: Getting this device information Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 03 12:36:15 rivoplus volumio[3190]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 03 12:36:15 rivoplus volumio[3190]: info: Received Get System Info Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:36:15 rivoplus volumio[3190]: info: Discovery: Getting this device information Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:15 rivoplus volumio[3190]: info: Listing playlists Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:36:15 rivoplus volumio[3190]: info: CoreStateMachine::getQueue Sep 03 12:36:15 rivoplus volumio[3190]: info: CorePlayQueue::getQueue Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:16 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:36:26 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 03 12:36:26 rivoplus volumio[3190]: info: Received Get System Info Sep 03 12:36:26 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:36:26 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:36:26 rivoplus volumio[3190]: info: Discovery: Getting this device information Sep 03 12:36:26 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:26 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:36:36 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 03 12:36:36 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:36:44 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 03 12:36:44 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:36:55 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:36:55 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:36:55 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:36:55 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:36:55 rivoplus volumio[3190]: info: Sep 03 12:36:55 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 29ms Sep 03 12:36:55 rivoplus volumio[3190]: info: sendMpdCommand status took 23 milliseconds Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 20ms Sep 03 12:36:55 rivoplus volumio[3190]: info: sendMpdCommand status took 17 milliseconds Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 16ms Sep 03 12:36:55 rivoplus volumio[3190]: info: sendMpdCommand status took 13 milliseconds Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 12ms Sep 03 12:36:55 rivoplus volumio[3190]: info: sendMpdCommand status took 10 milliseconds Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:36:55 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 03 12:36:55 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 03 12:36:55 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:36:55 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5161488,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:36:55 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:36:55 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:36:55 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5161488,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:36:55 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:36:55 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:36:55 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5161488,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:36:55 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:36:55 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:36:55 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5161614,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:36:55 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:36:55 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:36:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:36:55 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 188ms Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 179ms Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 177ms Sep 03 12:36:55 rivoplus volumio[3190]: info: ------------------------------ 175ms Sep 03 12:36:56 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:06 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:06 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:06 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:06 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:06 rivoplus volumio[3190]: info: Sep 03 12:37:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 19ms Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand status took 17 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 17ms Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand status took 14 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 13ms Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand status took 10 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 9ms Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand status took 7 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5172735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mark Ambor - Who Knows","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:37:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:37:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5172735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mark Ambor - Who Knows","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:37:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:37:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5172735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mark Ambor - Who Knows","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:37:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:37:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5172735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mark Ambor - Who Knows","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 03 12:37:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 03 12:37:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 139ms Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 137ms Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 134ms Sep 03 12:37:06 rivoplus volumio[3190]: info: ------------------------------ 132ms Sep 03 12:37:18 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 03 12:37:18 rivoplus volumio[3190]: info: [1756895838121] [80s80s] handleBrowseUri curUri: 80s80s Sep 03 12:37:18 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:37:19 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 03 12:37:19 rivoplus volumio[3190]: info: [1756895839888] [80s80s] handleBrowseUri curUri: 80s80s/nineties Sep 03 12:37:19 rivoplus volumio[3190]: info: [1756895839889] [80s80s] getRadioContent url: nineties Sep 03 12:37:19 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:37:24 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::ClearQueue Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::stop Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::updateTrackBlock Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrackBlock Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::serviceStop Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::serviceStop Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844295] ControllerWebradio::stop Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::clearPlayQueue Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::saveQueue Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushQueue Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::addQueueItems Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::addQueueItems Sep 03 12:37:24 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:37:24 rivoplus volumio[3190]: info: Adding Item to queue: webnineties/5 Sep 03 12:37:24 rivoplus volumio[3190]: info: Exploding uri webnineties/5 in service 80s80s Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844305] [80s80s] explodeUri: webnineties/5 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushQueue Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::saveQueue Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::updateTrackBlock Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrackBlock Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPlay Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::play index 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::stop Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::play index undefined Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::startPlaybackTimer Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844350] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/boygroup/mp3-192/volumio Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844352] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=149&count=2 Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand stop took 106 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand status took 14 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand status took 12 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand status took 10 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand status took 8 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:24 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 03 12:37:24 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 144ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 141ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 140ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 138ms Sep 03 12:37:24 rivoplus volumio[3190]: info: MCU Signalled Playback Inactive Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844751] [80s80s] received new event containing 2 songs. Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand stop took 4 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand clear took 6 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: error: updateQueue error: null Sep 03 12:37:24 rivoplus volumio[3190]: error: updateQueue error: null Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 18ms Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand consume 1 took 12 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 11ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 9ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 7ms Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844785] [80s80s] adding url: http://streams.90s90s.de/boygroup/mp3-192/volumio Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/boygroup/mp3-192/volumio" Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand add "http://streams.90s90s.de/boygroup/mp3-192/volumio" took 3 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand play Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: Sep 03 12:37:24 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:24 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 25ms Sep 03 12:37:24 rivoplus volumio[3190]: info: sendMpdCommand play took 13 milliseconds Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 12ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 7ms Sep 03 12:37:24 rivoplus volumio[3190]: info: ------------------------------ 4ms Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844814] [80s80s] Pushing the next song state: Backstreet Boys - Quit Playing Games (With My Heart) Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music114/v4/10/1b/3c/101b3cbe-eb61-35db-0f3a-36a859928f63/source/600x600bb.jpg","name":"Backstreet Boys - Quit Playing Games (With My Heart)","title":"Quit Playing Games (With My Heart)","artist":"90s90s Boygroups","album":"","streaming":true,"disableUiControls":true,"duration":"226","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 03 12:37:24 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 03 12:37:24 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus stop Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844821] [80s80s] PlayNextTrack API delay: 30 Sep 03 12:37:24 rivoplus volumio[3190]: info: [1756895844851] [80s80s] Setting timer to: 226000 milliseconds. Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:24 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 1 Sep 03 12:37:25 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 23424, instance 1, run: true Sep 03 12:37:26 rivoplus volumio[3190]: info: Executing endpoint metavolumio Sep 03 12:37:26 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 03 12:37:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 03 12:37:27 rivoplus volumio[3190]: info: camilladsp stopping service pid 23424... Sep 03 12:37:27 rivoplus volumio[3190]: info: camilladsp service terminated, instance 1 Sep 03 12:37:27 rivoplus volumio[3190]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 03 12:37:27 rivoplus volumio[3190]: info: camilladsp service started and running in background, instance 1 Sep 03 12:37:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 03 12:37:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 03 12:37:27 rivoplus volumio[3190]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 98 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 95 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 93 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 92 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 30 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 34ms Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 29 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 24 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 22 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 9 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 151ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 148ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 146ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 143ms Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:27 rivoplus volumio[3190]: info: Sep 03 12:37:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:27 rivoplus volumio[3190]: error: FusionDsp - WebSocket error: [object Object] Sep 03 12:37:27 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 23452, instance 1, run: true Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 65 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 65 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 62 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 62 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 66 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 57ms Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 55 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 55ms Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 52 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 52ms Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand status took 49 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 130ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 128ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 118ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 116ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 113ms Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:27 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 83ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 80ms Sep 03 12:37:27 rivoplus volumio[3190]: info: ------------------------------ 77ms Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:30 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:30 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:30 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 03 12:37:30 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 03 12:37:30 rivoplus volumio[3190]: info: Sep 03 12:37:30 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 16ms Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand status took 15 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 14ms Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand status took 12 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 12ms Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand status took 12 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 11ms Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand status took 10 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:30 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:30 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:30 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:30 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:30 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 03 12:37:30 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:30 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 47ms Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 45ms Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 42ms Sep 03 12:37:30 rivoplus volumio[3190]: info: ------------------------------ 36ms Sep 03 12:37:38 rivoplus volumio[3190]: info: Preload queue cleared Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreStateMachine::ClearQueue Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreStateMachine::stop Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreStateMachine::updateTrackBlock Sep 03 12:37:38 rivoplus volumio[3190]: info: CorePlayQueue::getTrackBlock Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 03 12:37:38 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:37:38 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 03 12:37:38 rivoplus volumio[3190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 12:37:38 rivoplus volumio[3190]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Sep 03 12:37:38 rivoplus volumio[3190]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Sep 03 12:37:38 rivoplus volumio[3190]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Sep 03 12:37:38 rivoplus volumio[3190]: at /volumio/app/index.js:326:81 Sep 03 12:37:38 rivoplus volumio[3190]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Sep 03 12:37:38 rivoplus volumio[3190]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Sep 03 12:37:38 rivoplus volumio[3190]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Sep 03 12:37:38 rivoplus volumio[3190]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:532:22) Sep 03 12:37:38 rivoplus volumio[3190]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1245:12) Sep 03 12:37:38 rivoplus volumio[3190]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:275:8) Sep 03 12:37:38 rivoplus volumio[3190]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Sep 03 12:37:38 rivoplus volumio[3190]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Sep 03 12:37:38 rivoplus volumio[3190]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Sep 03 12:37:38 rivoplus volumio[3190]: at Socket.emit (events.js:400:28) Sep 03 12:37:38 rivoplus volumio[3190]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 03 12:37:38 rivoplus volumio[3190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 03 12:37:38 rivoplus volumio[3190]: code: 'NumberExpected' Sep 03 12:37:38 rivoplus volumio[3190]: } Sep 03 12:37:38 rivoplus volumio[3190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 12:37:39 rivoplus sudo[23531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 12:36 Sep 03 12:37:39 rivoplus sudo[23531]: 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="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"