-- Logs begin at Sat 2025-07-26 05:23:19 EDT, end at Thu 2025-10-16 17:26:53 EDT. -- Oct 16 17:25:05 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:05 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:05 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:05 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:05 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:05 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:25:06 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32:3000 from 192.168.86.249 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 16 17:25:06 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32 from 192.168.86.249 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.208 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:06 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 17:25:06 rivoplus volumio[3193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 17:25:06 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:06 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:06 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:06 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:06 rivoplus volumio[3193]: info: Listing playlists Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:06 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:06 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:07 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 17:25:07 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:25:07 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:25:07 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:25:09 rivoplus kernel: [35B blob data] Oct 16 17:25:09 rivoplus kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 2 Oct 16 17:25:09 rivoplus kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER CONNECTED : 1a:f6:8b:aa:a7:b7 on primary ndev Oct 16 17:25:10 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32:3000 from 192.168.86.249 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 16 17:25:14 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 17:25:16 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 17:25:18 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:18 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:18 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:18 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:18 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:18 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPause Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::pause Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::servicePause Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::servicePause Oct 16 17:25:27 rivoplus volumio[3193]: info: [1760649927260] ControllerUPNPBrowser::pause Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 17:25:27 rivoplus volumio[3193]: info: Oct 16 17:25:27 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:27 rivoplus volumio[3193]: info: sendMpdCommand pause took 10 milliseconds Oct 16 17:25:27 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:27 rivoplus volumio[3193]: info: Oct 16 17:25:27 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:27 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:27 rivoplus volumio[3193]: info: sendMpdCommand status took 4 milliseconds Oct 16 17:25:27 rivoplus volumio[3193]: info: sendMpdCommand status took 2 milliseconds Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:27 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 17:25:27 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:27 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:27 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":169196,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:27 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService pause Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:27 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:27 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":169196,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:27 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService pause Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:27 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:27 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:27 rivoplus volumio[3193]: info: ------------------------------ 71ms Oct 16 17:25:27 rivoplus volumio[3193]: info: ------------------------------ 66ms Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:27 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:27 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:27 rivoplus volumio[3193]: info: MCU Signalled Playback Inactive Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPlay Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::play index undefined Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::startPlaybackTimer Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: [1760649934737] ControllerUPNPBrowser::resume Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand play Oct 16 17:25:34 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 16 17:25:34 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 16 17:25:34 rivoplus volumio[3193]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 16 17:25:34 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 16 17:25:34 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 16 17:25:34 rivoplus volumio[3193]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 16 17:25:34 rivoplus volumio[3193]: info: Oct 16 17:25:34 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:34 rivoplus volumio[3193]: info: sendMpdCommand play took 56 milliseconds Oct 16 17:25:34 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:34 rivoplus volumio[3193]: info: Oct 16 17:25:34 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:34 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:34 rivoplus volumio[3193]: info: sendMpdCommand status took 5 milliseconds Oct 16 17:25:34 rivoplus volumio[3193]: info: sendMpdCommand status took 3 milliseconds Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:34 rivoplus volumio[3193]: error: FusionDsp - WebSocket error: [object Object] Oct 16 17:25:34 rivoplus volumio[3193]: error: FusionDsp - WebSocket error: [object Object] Oct 16 17:25:34 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 17:25:34 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:34 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:34 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":170431,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:34 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:34 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:34 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":170431,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:34 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus play Oct 16 17:25:34 rivoplus volumio[3193]: info: Received an update from plugin. extracting info from payload Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:34 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:34 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:34 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:34 rivoplus volumio[3193]: info: ------------------------------ 70ms Oct 16 17:25:34 rivoplus volumio[3193]: info: ------------------------------ 69ms Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:34 rivoplus volumio[3193]: info: Signalling Playback active due to playback status change Oct 16 17:25:35 rivoplus volumio[3193]: info: MCU Signalled Playback Active Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPause Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::pause Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::servicePause Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::servicePause Oct 16 17:25:39 rivoplus volumio[3193]: info: [1760649939699] ControllerUPNPBrowser::pause Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 17:25:39 rivoplus volumio[3193]: info: Oct 16 17:25:39 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:39 rivoplus volumio[3193]: info: sendMpdCommand pause took 5 milliseconds Oct 16 17:25:39 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:39 rivoplus volumio[3193]: info: Oct 16 17:25:39 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:39 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:39 rivoplus volumio[3193]: info: sendMpdCommand status took 4 milliseconds Oct 16 17:25:39 rivoplus volumio[3193]: info: sendMpdCommand status took 2 milliseconds Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:39 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 17:25:39 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:39 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:39 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":170935,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:39 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService pause Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:39 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:39 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":170935,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:39 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService pause Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:39 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:39 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:39 rivoplus volumio[3193]: info: ------------------------------ 48ms Oct 16 17:25:39 rivoplus volumio[3193]: info: ------------------------------ 46ms Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:39 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:39 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:39 rivoplus volumio[3193]: info: MCU Signalled Playback Inactive Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPlay Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::play index undefined Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::startPlaybackTimer Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: [1760649943339] ControllerUPNPBrowser::resume Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand play Oct 16 17:25:43 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 16 17:25:43 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 16 17:25:43 rivoplus volumio[3193]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 16 17:25:43 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 16 17:25:43 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 16 17:25:43 rivoplus volumio[3193]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 16 17:25:43 rivoplus volumio[3193]: info: Oct 16 17:25:43 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:43 rivoplus volumio[3193]: info: sendMpdCommand play took 35 milliseconds Oct 16 17:25:43 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:43 rivoplus volumio[3193]: info: Oct 16 17:25:43 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:43 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:43 rivoplus volumio[3193]: info: sendMpdCommand status took 5 milliseconds Oct 16 17:25:43 rivoplus volumio[3193]: info: sendMpdCommand status took 3 milliseconds Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:43 rivoplus volumio[3193]: error: FusionDsp - WebSocket error: [object Object] Oct 16 17:25:43 rivoplus volumio[3193]: error: FusionDsp - WebSocket error: [object Object] Oct 16 17:25:43 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 17:25:43 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:43 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172170,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:43 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:43 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:43 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172170,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:43 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus play Oct 16 17:25:43 rivoplus volumio[3193]: info: Received an update from plugin. extracting info from payload Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:43 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:43 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:43 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:43 rivoplus volumio[3193]: info: ------------------------------ 82ms Oct 16 17:25:43 rivoplus volumio[3193]: info: ------------------------------ 80ms Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:43 rivoplus volumio[3193]: info: Signalling Playback active due to playback status change Oct 16 17:25:43 rivoplus volumio[3193]: info: MCU Signalled Playback Active Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPause Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::pause Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::servicePause Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::servicePause Oct 16 17:25:44 rivoplus volumio[3193]: info: [1760649944130] ControllerUPNPBrowser::pause Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 17:25:44 rivoplus volumio[3193]: info: sendMpdCommand pause took 2 milliseconds Oct 16 17:25:44 rivoplus volumio[3193]: info: Oct 16 17:25:44 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:44 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:44 rivoplus volumio[3193]: info: Oct 16 17:25:44 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:25:44 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:25:44 rivoplus volumio[3193]: info: sendMpdCommand status took 3 milliseconds Oct 16 17:25:44 rivoplus volumio[3193]: info: sendMpdCommand status took 2 milliseconds Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:25:44 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 17:25:44 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:44 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:25:44 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":172673,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:44 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService pause Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:44 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:44 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":172673,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:25:44 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService pause Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:44 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:25:44 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::stPlaybackTimer Oct 16 17:25:44 rivoplus volumio[3193]: info: ------------------------------ 45ms Oct 16 17:25:44 rivoplus volumio[3193]: info: ------------------------------ 44ms Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:44 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:44 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:44 rivoplus volumio[3193]: info: MCU Signalled Playback Inactive Oct 16 17:25:48 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:48 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:48 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:48 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:48 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:48 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:25:50 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 17:25:50 rivoplus volumio[3193]: info: Preload queue cleared Oct 16 17:25:51 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 17:25:52 rivoplus volumio[3193]: info: Preload queue cleared Oct 16 17:25:56 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:56 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:56 rivoplus sudo[22267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:25:56 rivoplus sudo[22267]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:56 rivoplus sudo[22267]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:56 rivoplus sudo[22277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:25:56 rivoplus sudo[22277]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:56 rivoplus sudo[22277]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:56 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32 from 192.168.86.249 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.208 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 16 17:25:56 rivoplus sudo[22303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:25:56 rivoplus sudo[22303]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:56 rivoplus sudo[22303]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:56 rivoplus sudo[22306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:25:56 rivoplus sudo[22306]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:56 rivoplus sudo[22306]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:57 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32 from 192.168.86.249 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.208 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:57 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:25:57 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:25:57 rivoplus volumio[3193]: info: Listing playlists Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 17:25:57 rivoplus volumio[3193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 17:25:57 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:57 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:57 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:57 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 17:25:57 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:25:58 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 17:25:58 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:25:58 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:25:58 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:58 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:58 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:58 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:58 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:58 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:25:59 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:25:59 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:25:59 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:25:59 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:25:59 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:25:59 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:59 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:25:59 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:07 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 17:26:14 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:26:15 rivoplus volumio[3193]: No protocol specified Oct 16 17:26:15 rivoplus volumio[3193]: xcb_connection_has_error() returned true Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 17:26:15 rivoplus volumio[3193]: No protocol specified Oct 16 17:26:15 rivoplus volumio[3193]: xcb_connection_has_error() returned true Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 17:26:15 rivoplus volumio[3193]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 16 17:26:15 rivoplus volumio[3193]: info: Received Get System Version Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 17:26:15 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:15 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:15 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:26:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:26:18 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:18 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:18 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:18 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:18 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:18 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPlay Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::play index undefined Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::startPlaybackTimer Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: [1760649981654] ControllerUPNPBrowser::resume Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand play Oct 16 17:26:21 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate, raw: Oct 16 17:26:21 rivoplus volumio[3193]: error: FusionDsp - invalid sample rate Oct 16 17:26:21 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 16 17:26:21 rivoplus volumio[3193]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 16 17:26:21 rivoplus volumio[3193]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 16 17:26:21 rivoplus volumio[3193]: info: Oct 16 17:26:21 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:26:21 rivoplus volumio[3193]: info: sendMpdCommand play took 36 milliseconds Oct 16 17:26:21 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:26:21 rivoplus volumio[3193]: info: Oct 16 17:26:21 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Oct 16 17:26:21 rivoplus volumio[3193]: info: ControllerMpd::getState Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Oct 16 17:26:21 rivoplus volumio[3193]: info: sendMpdCommand status took 4 milliseconds Oct 16 17:26:21 rivoplus volumio[3193]: info: sendMpdCommand status took 3 milliseconds Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 17:26:21 rivoplus volumio[3193]: error: FusionDsp - WebSocket error: [object Object] Oct 16 17:26:21 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 17:26:21 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:26:21 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Oct 16 17:26:21 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173909,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:26:21 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus pause Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:26:21 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:26:21 rivoplus volumio[3193]: info: ControllerMpd::pushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173909,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC0xOTQ5Mg","artist":null,"album":null,"uri":"http://192.168.86.30:10243/WMPNSSv4/1271992405/0_NC0xOTQ5Mg","trackType":""} Oct 16 17:26:21 rivoplus volumio[3193]: verbose: CURRENT POSITION 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus play Oct 16 17:26:21 rivoplus volumio[3193]: info: Received an update from plugin. extracting info from payload Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:26:21 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::pushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:21 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output update for this device Oct 16 17:26:21 rivoplus volumio[3193]: info: MRS: Pushing multiroomSync output Oct 16 17:26:21 rivoplus volumio[3193]: info: ------------------------------ 82ms Oct 16 17:26:21 rivoplus volumio[3193]: info: ------------------------------ 80ms Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:26:21 rivoplus volumio[3193]: info: Signalling Playback active due to playback status change Oct 16 17:26:21 rivoplus volumio[3193]: info: MCU Signalled Playback Active Oct 16 17:26:30 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:30 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:31 rivoplus sudo[22388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:26:31 rivoplus sudo[22388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:26:31 rivoplus sudo[22388]: pam_unix(sudo:session): session closed for user root Oct 16 17:26:31 rivoplus sudo[22391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:26:31 rivoplus sudo[22391]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:26:31 rivoplus sudo[22391]: pam_unix(sudo:session): session closed for user root Oct 16 17:26:31 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32 from 192.168.86.249 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.208 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 17:26:31 rivoplus sudo[22396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:26:31 rivoplus sudo[22396]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:26:31 rivoplus sudo[22396]: pam_unix(sudo:session): session closed for user root Oct 16 17:26:31 rivoplus sudo[22400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:26:31 rivoplus sudo[22400]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:26:31 rivoplus sudo[22400]: pam_unix(sudo:session): session closed for user root Oct 16 17:26:31 rivoplus volumio[3193]: verbose: New Socket.io Connection to 192.168.86.32 from 192.168.86.249 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.208 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:31 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 17:26:31 rivoplus volumio[3193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 17:26:31 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:31 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:31 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:31 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:31 rivoplus volumio[3193]: info: Listing playlists Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 17:26:31 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:26:33 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:33 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:33 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:26:33 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:33 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:33 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:33 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:37 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetQueue Oct 16 17:26:37 rivoplus volumio[3193]: info: CoreStateMachine::getQueue Oct 16 17:26:37 rivoplus volumio[3193]: info: CorePlayQueue::getQueue Oct 16 17:26:41 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 17:26:42 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:26:42 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 17:26:42 rivoplus volumio[3193]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:26:48 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:48 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:48 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:48 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:48 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:48 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 17:26:49 rivoplus volumio[3193]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 16 17:26:49 rivoplus volumio[3193]: info: Received Get System Version Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 17:26:49 rivoplus volumio[3193]: info: Received Get System Info Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:26:49 rivoplus volumio[3193]: info: Discovery: Getting this device information Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::volumioGetState Oct 16 17:26:49 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 8 Oct 16 17:26:49 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:26:50 rivoplus volumio[3193]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Oct 16 17:26:52 rivoplus volumio[3193]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Oct 16 17:26:52 rivoplus volumio[3193]: info: FusionDsp - Stopping FusionDsp service Oct 16 17:26:52 rivoplus volumio[3193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:26:52 rivoplus volumio[3193]: TypeError: Cannot read property 'stop' of null Oct 16 17:26:52 rivoplus volumio[3193]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Oct 16 17:26:52 rivoplus volumio[3193]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 16 17:26:52 rivoplus volumio[3193]: at /volumio/app/pluginmanager.js:956:12 Oct 16 17:26:52 rivoplus volumio[3193]: at ChildProcess.exithandler (child_process.js:374:7) Oct 16 17:26:52 rivoplus volumio[3193]: at ChildProcess.emit (events.js:400:28) Oct 16 17:26:52 rivoplus volumio[3193]: at maybeClose (internal/child_process.js:1088:16) Oct 16 17:26:52 rivoplus volumio[3193]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Oct 16 17:26:52 rivoplus volumio[3193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:26:53 rivoplus sudo[22455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 17:25 Oct 16 17:26:53 rivoplus sudo[22455]: 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"