Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: Received Get System Info Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: Discovery: Getting this device information Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:42 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPlay Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::play index 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::stop Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::play index undefined Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::startPlaybackTimer Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand stop took 2 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces system playlist update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Ignoring MPD Status Update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand clear took 0 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac" Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: updateQueue error: null Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 1ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces system playlist update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Ignoring MPD Status Update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces system playlist update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Ignoring MPD Status Update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces system playlist update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Ignoring MPD Status Update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand add "NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac" took 1 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 1ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 1ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces system playlist update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Ignoring MPD Status Update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces system playlist update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Ignoring MPD Status Update Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 1ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand play took 1 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 1ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 1ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces state update: player Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::getState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces state update: player Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::getState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces state update: player Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::getState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces state update: player Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand status took 6 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::getState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces state update: player Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::getState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand status took 7 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand status took 7 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Nov 05 17:27:44 volumio-4.062 volumio[1065]: ---------------------------- MPD announces state update: player Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::getState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand status took 3 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand status took 3 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseTrackInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseTrackInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseTrackInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::servicePushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cada vez cadáver","artist":"Fito & Fitipaldis","album":"Cada vez cadáver","uri":"NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac","trackType":"flac"} Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: CURRENT POSITION 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState stateService play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::servicePushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cada vez cadáver","artist":"Fito & Fitipaldis","album":"Cada vez cadáver","uri":"NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac","trackType":"flac"} Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: CURRENT POSITION 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState stateService play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState currentStatus play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Received an update from plugin. extracting info from payload Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::servicePushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cada vez cadáver","artist":"Fito & Fitipaldis","album":"Cada vez cadáver","uri":"NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac","trackType":"flac"} Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: CURRENT POSITION 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState stateService play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState currentStatus play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Received an update from plugin. extracting info from payload Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 16ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 23ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 22ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand status took 14 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseState Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseTrackInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseTrackInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::servicePushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cada vez cadáver","artist":"Fito & Fitipaldis","album":"Cada vez cadáver","uri":"NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac","trackType":"flac"} Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: CURRENT POSITION 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState stateService play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState currentStatus play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Received an update from plugin. extracting info from payload Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::servicePushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cada vez cadáver","artist":"Fito & Fitipaldis","album":"Cada vez cadáver","uri":"NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac","trackType":"flac"} Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: CURRENT POSITION 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState stateService play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState currentStatus play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Received an update from plugin. extracting info from payload Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 26ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 25ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: sendMpdCommand playlistinfo took 29 milliseconds Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: ControllerMpd::parseTrackInfo Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ControllerMpd::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::servicePushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cada vez cadáver","artist":"Fito & Fitipaldis","album":"Cada vez cadáver","uri":"NAS/Music/Rock/Fito y Fitipaldis/Cada vez cadáver [196319288] [2021]/01 - Fito y Fitipaldis - Cada vez cadáver.flac","trackType":"flac"} Nov 05 17:27:44 volumio-4.062 volumio[1065]: verbose: CURRENT POSITION 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState stateService play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::syncState currentStatus play Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: Received an update from plugin. extracting info from payload Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreStateMachine::pushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioPushState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: MRS: Pushing multiroomSync output Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: ------------------------------ 52ms Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: info: FusionDsp - Volumio is playing Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: CoreCommandRouter::volumioGetState Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: CorePlayQueue::getTrack 0 Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: MRS: Starting browser stream Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: MRS: Setting this device as single Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: Nov 05 17:27:47 volumio-4.062 volumio[1065]: [1762360067597] ---------------------------- Setting Multiroom Single Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: MRS: disable multiroom output Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioDiscard Nov 05 17:27:47 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:27:48 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: MRS: STOPPING volumioStreaming Nov 05 17:27:49 volumio-4.062 sudo[1167003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 05 17:27:49 volumio-4.062 sudo[1167003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:49 volumio-4.062 sudo[1167005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream2.ts Nov 05 17:27:49 volumio-4.062 sudo[1167005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:49 volumio-4.062 sudo[1167005]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: MRS: Removed streaming files Nov 05 17:27:49 volumio-4.062 systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Nov 05 17:27:49 volumio-4.062 systemd[1]: volumioStreaming.service: Killing process 1149252 (ffmpeg) with signal SIGKILL. Nov 05 17:27:49 volumio-4.062 systemd[1]: volumioStreaming.service: Killing process 1166966 (ffmpeg) with signal SIGKILL. Nov 05 17:27:49 volumio-4.062 systemd[1]: volumioStreaming.service: Deactivated successfully. Nov 05 17:27:49 volumio-4.062 systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Nov 05 17:27:49 volumio-4.062 sudo[1167003]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: MRS: volumioStreaming STOPPED Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: Executing endpoint metavolumio Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: Executing endpoint metavolumio Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: Executing endpoint metavolumio Nov 05 17:27:49 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: Starting cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: Enabled audio output: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: Setting this device as Streaming Server Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: Nov 05 17:27:53 volumio-4.062 volumio[1065]: [1762360073817] ---------------------------- MRS: Setting Streaming Server Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: enable multiroom server output Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioMultiRoom Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: STARTING volumioStreaming Nov 05 17:27:53 volumio-4.062 sudo[1167028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 05 17:27:53 volumio-4.062 sudo[1167028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:53 volumio-4.062 sudo[1167028]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:53 volumio-4.062 sudo[1167030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Nov 05 17:27:53 volumio-4.062 sudo[1167030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: Connected to cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:53 volumio-4.062 systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Nov 05 17:27:53 volumio-4.062 sudo[1167030]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:53 volumio-4.062 volumio[1065]: info: MRS: volumioStreaming STARTED Nov 05 17:27:53 volumio-4.062 sudo[1167034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 05 17:27:53 volumio-4.062 sudo[1167034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:53 volumio-4.062 sudo[1167034]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: MRS: Setting this device as single Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: Nov 05 17:27:56 volumio-4.062 volumio[1065]: [1762360076132] ---------------------------- Setting Multiroom Single Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: MRS: disable multiroom output Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioDiscard Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Nov 05 17:27:56 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:56 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:56 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 05 17:27:56 volumio-4.062 volumio[1065]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 05 17:27:57 volumio-4.062 volumio[1065]: info: MRS: STOPPING volumioStreaming Nov 05 17:27:57 volumio-4.062 volumio[1065]: info: MRS: Stopping cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:57 volumio-4.062 sudo[1167087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 05 17:27:57 volumio-4.062 sudo[1167087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:57 volumio-4.062 sudo[1167089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream2.ts Nov 05 17:27:57 volumio-4.062 sudo[1167089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:57 volumio-4.062 sudo[1167089]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:57 volumio-4.062 volumio[1065]: info: MRS: Removed streaming files Nov 05 17:27:57 volumio-4.062 systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Nov 05 17:27:57 volumio-4.062 systemd[1]: volumioStreaming.service: Killing process 1167036 (ffmpeg) with signal SIGKILL. Nov 05 17:27:57 volumio-4.062 systemd[1]: volumioStreaming.service: Killing process 1167045 (ffmpeg) with signal SIGKILL. Nov 05 17:27:57 volumio-4.062 systemd[1]: volumioStreaming.service: Deactivated successfully. Nov 05 17:27:57 volumio-4.062 systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Nov 05 17:27:57 volumio-4.062 sudo[1167087]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:57 volumio-4.062 volumio[1065]: info: MRS: volumioStreaming STOPPED Nov 05 17:27:58 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:27:58 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:27:58 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: Starting cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: Enabled audio output: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: Setting this device as Streaming Server Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: Nov 05 17:27:59 volumio-4.062 volumio[1065]: [1762360079272] ---------------------------- MRS: Setting Streaming Server Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: enable multiroom server output Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioMultiRoom Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: STARTING volumioStreaming Nov 05 17:27:59 volumio-4.062 sudo[1167103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 05 17:27:59 volumio-4.062 sudo[1167103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:59 volumio-4.062 sudo[1167103]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:59 volumio-4.062 sudo[1167105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Nov 05 17:27:59 volumio-4.062 sudo[1167105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: Connected to cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:27:59 volumio-4.062 systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Nov 05 17:27:59 volumio-4.062 sudo[1167105]: pam_unix(sudo:session): session closed for user root Nov 05 17:27:59 volumio-4.062 volumio[1065]: info: MRS: volumioStreaming STARTED Nov 05 17:27:59 volumio-4.062 sudo[1167111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 05 17:27:59 volumio-4.062 sudo[1167111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:27:59 volumio-4.062 sudo[1167111]: pam_unix(sudo:session): session closed for user root Nov 05 17:28:02 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:02 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:02 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:04 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:04 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:04 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:04 volumio-4.062 volumio[1065]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Nov 05 17:28:06 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:06 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:06 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:08 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:08 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:08 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:10 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:10 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:10 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:12 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:12 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:12 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:14 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:14 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:14 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:16 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:16 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:16 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:18 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:18 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:18 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:20 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:20 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:20 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:22 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:22 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:22 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:24 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:24 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:24 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:24 volumio-4.062 volumio[1065]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Nov 05 17:28:26 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:26 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:26 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:28 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:28 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:28 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:30 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:30 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:30 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:32 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:32 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:32 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:32 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Nov 05 17:28:32 volumio-4.062 volumio[1065]: info: MRS: Setting this device as single Nov 05 17:28:32 volumio-4.062 volumio[1065]: info: Nov 05 17:28:32 volumio-4.062 volumio[1065]: [1762360112503] ---------------------------- Setting Multiroom Single Nov 05 17:28:32 volumio-4.062 volumio[1065]: info: MRS: disable multiroom output Nov 05 17:28:32 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioDiscard Nov 05 17:28:32 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: MRS: STOPPING volumioStreaming Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: MRS: Stopping cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:28:33 volumio-4.062 sudo[1167288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 05 17:28:33 volumio-4.062 sudo[1167288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:28:33 volumio-4.062 sudo[1167290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream29.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts Nov 05 17:28:33 volumio-4.062 sudo[1167290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:28:33 volumio-4.062 sudo[1167290]: pam_unix(sudo:session): session closed for user root Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: MRS: Removed streaming files Nov 05 17:28:33 volumio-4.062 systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Nov 05 17:28:33 volumio-4.062 systemd[1]: volumioStreaming.service: Killing process 1167113 (ffmpeg) with signal SIGKILL. Nov 05 17:28:33 volumio-4.062 systemd[1]: volumioStreaming.service: Killing process 1167121 (ffmpeg) with signal SIGKILL. Nov 05 17:28:33 volumio-4.062 systemd[1]: volumioStreaming.service: Deactivated successfully. Nov 05 17:28:33 volumio-4.062 systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Nov 05 17:28:33 volumio-4.062 systemd[1]: volumioStreaming.service: Consumed 1.716s CPU time. Nov 05 17:28:33 volumio-4.062 sudo[1167288]: pam_unix(sudo:session): session closed for user root Nov 05 17:28:33 volumio-4.062 volumio[1065]: info: MRS: volumioStreaming STOPPED Nov 05 17:28:34 volumio-4.062 volumio[1065]: info: FusionDsp - Clipping Monitor started Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: Starting cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: Enabled audio output: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: Setting this device as Streaming Server Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: Nov 05 17:28:35 volumio-4.062 volumio[1065]: [1762360115258] ---------------------------- MRS: Setting Streaming Server Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: enable multiroom server output Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioMultiRoom Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: STARTING volumioStreaming Nov 05 17:28:35 volumio-4.062 sudo[1167302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 05 17:28:35 volumio-4.062 sudo[1167302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:28:35 volumio-4.062 sudo[1167304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Nov 05 17:28:35 volumio-4.062 sudo[1167304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:28:35 volumio-4.062 sudo[1167302]: pam_unix(sudo:session): session closed for user root Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: Connected to cast device: Stereo-Hub-c63f957bc0269182432cc3d8cdfb242b Nov 05 17:28:35 volumio-4.062 systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Nov 05 17:28:35 volumio-4.062 sudo[1167304]: pam_unix(sudo:session): session closed for user root Nov 05 17:28:35 volumio-4.062 volumio[1065]: info: MRS: volumioStreaming STARTED Nov 05 17:28:35 volumio-4.062 sudo[1167308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 05 17:28:35 volumio-4.062 sudo[1167308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 05 17:28:35 volumio-4.062 sudo[1167308]: pam_unix(sudo:session): session closed for user root Nov 05 17:28:38 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:40 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:42 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:44 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:44 volumio-4.062 volumio[1065]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Nov 05 17:28:46 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:48 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:50 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:52 volumio-4.062 volumio[1065]: error: FusionDsp - Monitor WebSocket error: [object Object] Nov 05 17:28:53 volumio-4.062 volumio[1065]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 17:28:53 volumio-4.062 volumio[1065]: TypeError: Cannot read properties of undefined (reading 'on') Nov 05 17:28:53 volumio-4.062 volumio[1065]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29945 Nov 05 17:28:53 volumio-4.062 volumio[1065]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Nov 05 17:28:53 volumio-4.062 volumio[1065]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Nov 05 17:28:53 volumio-4.062 volumio[1065]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at fn.emit (node:events:526:35) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at Channel.emit (node:events:514:28) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at Client.emit (node:events:526:35) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at PacketStreamWrapper.emit (node:events:514:28) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at TLSSocket.emit (node:events:514:28) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at emitReadable_ (node:internal/streams/readable:609:12) Nov 05 17:28:53 volumio-4.062 volumio[1065]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) Nov 05 17:28:53 volumio-4.062 volumio[1065]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 17:28:54 volumio-4.062 sudo[1167426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-05 17:27' Nov 05 17:28:54 volumio-4.062 sudo[1167426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:33:08 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="434a4f7d2e5f6acafabfcca26b8e5834"