-- Logs begin at Fri 2025-06-27 12:05:25 CEST, end at Fri 2025-06-27 16:10:00 CEST. -- Jun 27 16:09:00 rivodu volumio[3413]: info: Executing endpoint metavolumio Jun 27 16:09:00 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 16:09:00 rivodu volumio[3413]: info: Executing endpoint metavolumio Jun 27 16:09:00 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 16:09:02 rivodu volumio[3413]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 27 16:09:02 rivodu volumio[3413]: info: Received Get System Version Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 16:09:02 rivodu volumio[3413]: info: Received Get System Info Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:02 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:02 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioStop Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::stop Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::stPlaybackTimer Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::updateTrackBlock Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrackBlock Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::serviceStop Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::serviceStop Jun 27 16:09:04 rivodu volumio[3413]: info: [1751033344625] ControllerWebradio::stop Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 16:09:04 rivodu volumio[3413]: info: Jun 27 16:09:04 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:04 rivodu volumio[3413]: info: sendMpdCommand stop took 34 milliseconds Jun 27 16:09:04 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:04 rivodu volumio[3413]: info: Jun 27 16:09:04 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:04 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:04 rivodu volumio[3413]: info: sendMpdCommand status took 7 milliseconds Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:04 rivodu volumio[3413]: info: sendMpdCommand status took 16 milliseconds Jun 27 16:09:04 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:04 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 27 16:09:04 rivodu volumio[3413]: info: ------------------------------ 46ms Jun 27 16:09:04 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 27 16:09:04 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:04 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:04 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:04 rivodu volumio[3413]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 27 16:09:04 rivodu volumio[3413]: info: ------------------------------ 75ms Jun 27 16:09:04 rivodu volumio[3413]: info: MCU Signalled Playback Inactive Jun 27 16:09:06 rivodu volumio[3413]: info: Executing endpoint metavolumio Jun 27 16:09:06 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 16:09:09 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:09 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:09 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:09 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:09 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:09 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:09:14 rivodu volumio[3413]: info: CoreCommandRouter::volumioPlay Jun 27 16:09:14 rivodu volumio[3413]: info: CoreStateMachine::play index undefined Jun 27 16:09:14 rivodu volumio[3413]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 16:09:14 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:14 rivodu volumio[3413]: info: CoreStateMachine::startPlaybackTimer Jun 27 16:09:14 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:14 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 16:09:14 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 16:09:14 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 27 16:09:14 rivodu volumio[3413]: info: [1751033354558] ControllerWebradio::clearAddPlayTrack Jun 27 16:09:14 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 16:09:14 rivodu volumio[3413]: info: sendMpdCommand stop took 7 milliseconds Jun 27 16:09:14 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 16:09:14 rivodu volumio[3413]: info: Jun 27 16:09:14 rivodu volumio[3413]: ---------------------------- MPD announces system playlist update Jun 27 16:09:14 rivodu volumio[3413]: info: Ignoring MPD Status Update Jun 27 16:09:14 rivodu volumio[3413]: info: sendMpdCommand clear took 2 milliseconds Jun 27 16:09:14 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand load "https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein" Jun 27 16:09:14 rivodu volumio[3413]: info: Jun 27 16:09:14 rivodu volumio[3413]: ---------------------------- MPD announces system playlist update Jun 27 16:09:14 rivodu volumio[3413]: info: Ignoring MPD Status Update Jun 27 16:09:14 rivodu volumio[3413]: error: updateQueue error: null Jun 27 16:09:14 rivodu volumio[3413]: info: ------------------------------ 6ms Jun 27 16:09:15 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand add "https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein" Jun 27 16:09:15 rivodu volumio[3413]: error: updateQueue error: null Jun 27 16:09:15 rivodu volumio[3413]: info: Jun 27 16:09:15 rivodu volumio[3413]: ---------------------------- MPD announces system playlist update Jun 27 16:09:15 rivodu volumio[3413]: info: Ignoring MPD Status Update Jun 27 16:09:15 rivodu volumio[3413]: info: ------------------------------ 1359ms Jun 27 16:09:15 rivodu volumio[3413]: info: sendMpdCommand add "https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein" took 4 milliseconds Jun 27 16:09:15 rivodu volumio[3413]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 16:09:15 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand play Jun 27 16:09:15 rivodu volumio[3413]: info: Jun 27 16:09:15 rivodu volumio[3413]: ---------------------------- MPD announces system playlist update Jun 27 16:09:15 rivodu volumio[3413]: info: Ignoring MPD Status Update Jun 27 16:09:15 rivodu volumio[3413]: info: ------------------------------ 11ms Jun 27 16:09:15 rivodu volumio[3413]: info: sendMpdCommand play took 9 milliseconds Jun 27 16:09:15 rivodu volumio[3413]: info: ------------------------------ 5ms Jun 27 16:09:16 rivodu volumio[3413]: info: Jun 27 16:09:16 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:16 rivodu volumio[3413]: info: Jun 27 16:09:16 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:16 rivodu volumio[3413]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jun 27 16:09:16 rivodu volumio[3413]: info: FusionDsp - ---- read samplerate from file: 48000 Jun 27 16:09:16 rivodu volumio[3413]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 27 16:09:16 rivodu volumio[3413]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jun 27 16:09:16 rivodu volumio[3413]: info: FusionDsp - ---- read samplerate from file: 48000 Jun 27 16:09:16 rivodu volumio[3413]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 27 16:09:16 rivodu volumio[3413]: info: Jun 27 16:09:16 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand status took 32 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand status took 31 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:16 rivodu volumio[3413]: info: Jun 27 16:09:16 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand status took 8 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand status took 4 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:16 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:16 rivodu volumio[3413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Jun 27 16:09:16 rivodu volumio[3413]: verbose: CURRENT POSITION 0 Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::syncState stateService play Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::syncState currentStatus stop Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:16 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:16 rivodu volumio[3413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Jun 27 16:09:16 rivodu volumio[3413]: verbose: CURRENT POSITION 0 Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::syncState stateService play Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::syncState currentStatus play Jun 27 16:09:16 rivodu volumio[3413]: info: Received an update from plugin. extracting info from payload Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:16 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:16 rivodu volumio[3413]: info: ------------------------------ 69ms Jun 27 16:09:16 rivodu volumio[3413]: info: ------------------------------ 77ms Jun 27 16:09:16 rivodu volumio[3413]: error: FusionDsp - WebSocket error: [object Object] Jun 27 16:09:16 rivodu volumio[3413]: error: FusionDsp - WebSocket error: [object Object] Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 40 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 27 16:09:16 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:16 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:16 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:16 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:16 rivodu volumio[3413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Jun 27 16:09:16 rivodu volumio[3413]: verbose: CURRENT POSITION 0 Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::syncState stateService play Jun 27 16:09:16 rivodu volumio[3413]: info: CoreStateMachine::syncState currentStatus play Jun 27 16:09:17 rivodu volumio[3413]: info: Received an update from plugin. extracting info from payload Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr3-live.icecastssl.wdr.de/wdr/wdr3/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Jun 27 16:09:17 rivodu volumio[3413]: verbose: CURRENT POSITION 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::syncState stateService play Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::syncState currentStatus play Jun 27 16:09:17 rivodu volumio[3413]: info: Received an update from plugin. extracting info from payload Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: ------------------------------ 132ms Jun 27 16:09:17 rivodu volumio[3413]: info: ------------------------------ 131ms Jun 27 16:09:17 rivodu volumio[3413]: info: Signalling Playback active due to playback status change Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioStop Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::stop Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::stPlaybackTimer Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::updateTrackBlock Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrackBlock Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::serviceStop Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::serviceStop Jun 27 16:09:17 rivodu volumio[3413]: info: [1751033357182] ControllerWebradio::stop Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 16:09:17 rivodu volumio[3413]: info: MCU Signalled Playback Active Jun 27 16:09:17 rivodu volumio[3413]: info: Jun 27 16:09:17 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:17 rivodu volumio[3413]: info: sendMpdCommand stop took 30 milliseconds Jun 27 16:09:17 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:17 rivodu volumio[3413]: info: Jun 27 16:09:17 rivodu volumio[3413]: ---------------------------- MPD announces state update: player Jun 27 16:09:17 rivodu volumio[3413]: info: ControllerMpd::getState Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand status Jun 27 16:09:17 rivodu volumio[3413]: info: sendMpdCommand status took 5 milliseconds Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:17 rivodu volumio[3413]: info: sendMpdCommand status took 8 milliseconds Jun 27 16:09:17 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::parseState Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:17 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 27 16:09:17 rivodu volumio[3413]: info: ------------------------------ 38ms Jun 27 16:09:17 rivodu volumio[3413]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 27 16:09:17 rivodu volumio[3413]: verbose: ControllerMpd::parseTrackInfo Jun 27 16:09:17 rivodu volumio[3413]: info: ControllerMpd::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::servicePushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreStateMachine::pushState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioPushState Jun 27 16:09:17 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:17 rivodu volumio[3413]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 27 16:09:17 rivodu volumio[3413]: info: ------------------------------ 54ms Jun 27 16:09:17 rivodu volumio[3413]: info: MCU Signalled Playback Inactive Jun 27 16:09:19 rivodu volumio[3413]: info: Executing endpoint metavolumio Jun 27 16:09:19 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 16:09:22 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetQueue Jun 27 16:09:22 rivodu volumio[3413]: info: CoreStateMachine::getQueue Jun 27 16:09:22 rivodu volumio[3413]: info: CorePlayQueue::getQueue Jun 27 16:09:36 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:36 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:37 rivodu sudo[12075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 16:09:37 rivodu sudo[12075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 16:09:37 rivodu sudo[12075]: pam_unix(sudo:session): session closed for user root Jun 27 16:09:37 rivodu sudo[12078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 16:09:37 rivodu sudo[12078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 16:09:37 rivodu sudo[12078]: pam_unix(sudo:session): session closed for user root Jun 27 16:09:37 rivodu volumio[3413]: verbose: New Socket.io Connection to 192.168.178.63 from 192.168.178.49 UA: Mozilla/5.0 (Linux; Android 14; motorola edge 30 neo Build/U1SSMS34.31-64-10-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 27 16:09:37 rivodu sudo[12083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 16:09:37 rivodu sudo[12083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 16:09:37 rivodu sudo[12083]: pam_unix(sudo:session): session closed for user root Jun 27 16:09:37 rivodu sudo[12086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 16:09:37 rivodu sudo[12086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 16:09:37 rivodu sudo[12086]: pam_unix(sudo:session): session closed for user root Jun 27 16:09:37 rivodu volumio[3413]: verbose: New Socket.io Connection to 192.168.178.63 from 192.168.178.49 UA: Mozilla/5.0 (Linux; Android 14; motorola edge 30 neo Build/U1SSMS34.31-64-10-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:38 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 27 16:09:38 rivodu volumio[3413]: info: Received Get System Info Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:38 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:38 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:38 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:38 rivodu volumio[3413]: info: Listing playlists Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 27 16:09:38 rivodu volumio[3413]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 16:09:38 rivodu volumio[3413]: info: FusionDsp - Jun 27 16:09:38 rivodu volumio[3413]: info: FusionDsp - undefined Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 27 16:09:39 rivodu volumio[3413]: info: Received Get System Info Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:39 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:39 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:39 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:39 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:39 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:09:40 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 27 16:09:40 rivodu volumio[3413]: info: Received Get System Info Jun 27 16:09:40 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:40 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:40 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:40 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:40 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:40 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:09:44 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 27 16:09:44 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 27 16:09:44 rivodu volumio[3413]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 16:09:44 rivodu volumio[3413]: info: FusionDsp - Jun 27 16:09:44 rivodu volumio[3413]: info: FusionDsp - undefined Jun 27 16:09:47 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 16:09:55 rivodu volumio[3413]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 27 16:09:55 rivodu volumio[3413]: info: Received Get System Version Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 16:09:55 rivodu volumio[3413]: info: Received Get System Info Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 16:09:55 rivodu volumio[3413]: info: Discovery: Getting this device information Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::volumioGetState Jun 27 16:09:55 rivodu volumio[3413]: info: CorePlayQueue::getTrack 0 Jun 27 16:09:55 rivodu volumio[3413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 16:10:00 rivodu volumio[3413]: info: Starting Uninstall of plugin audio_interface - fusiondsp Jun 27 16:10:00 rivodu volumio[3413]: info: Uninstalling plugin fusiondsp Jun 27 16:10:00 rivodu volumio[3413]: info: FusionDsp - Stopping FusionDsp service Jun 27 16:10:00 rivodu volumio[3413]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 16:10:00 rivodu volumio[3413]: TypeError: Cannot read property 'stop' of null Jun 27 16:10:00 rivodu volumio[3413]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jun 27 16:10:00 rivodu volumio[3413]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 27 16:10:00 rivodu volumio[3413]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jun 27 16:10:00 rivodu volumio[3413]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jun 27 16:10:00 rivodu volumio[3413]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Jun 27 16:10:00 rivodu volumio[3413]: at Socket.emit (events.js:400:28) Jun 27 16:10:00 rivodu volumio[3413]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 27 16:10:00 rivodu volumio[3413]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 27 16:10:00 rivodu volumio[3413]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 16:10:00 rivodu sudo[12137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 16:09 Jun 27 16:10:00 rivodu sudo[12137]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 06:01:16 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="5a6f819282ea0c6f84bf87456dce95cb"