Aug 27 21:22:02 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::ClearQueue Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::stop Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::stPlaybackTimer Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::serviceStop Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::serviceStop Aug 27 21:22:02 living-room volumio[1233]: info: [1787883722030] ControllerTidal::stop Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::stop Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::clearPlayQueue Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::addQueueItems Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::addQueueItems Aug 27 21:22:02 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:02 living-room volumio[1233]: info: Adding Item to queue: tidal://song/100202039 Aug 27 21:22:02 living-room volumio[1233]: info: Using cached record of: tidal://song/100202039 Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.035-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.035-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPlay Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::play index 0 Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::addQueueItems Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::addQueueItems Aug 27 21:22:02 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:02 living-room volumio[1233]: info: Adding Item to queue: tidal://song/20767339 Aug 27 21:22:02 living-room volumio[1233]: info: Using cached record of: tidal://song/20767339 Aug 27 21:22:02 living-room volumio[1233]: info: Adding Item to queue: tidal://song/20750059 Aug 27 21:22:02 living-room volumio[1233]: info: Using cached record of: tidal://song/20750059 Aug 27 21:22:02 living-room volumio[1233]: info: Adding Item to queue: tidal://song/96854648 Aug 27 21:22:02 living-room volumio[1233]: info: Using cached record of: tidal://song/96854648 Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::stop Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::play index undefined Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: info: [1787883722067] ControllerTidal::clearAddPlayTrack Aug 27 21:22:02 living-room volumio[1233]: info: Getting stream with soundQuality HI_RES Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - Volumio is not playing Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - Clipped samples monitor stopped Aug 27 21:22:02 living-room volumio[1233]: info: Aug 27 21:22:02 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand stop took 60 milliseconds Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand status took 2 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.104-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.104-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/100202039 title="Hotel California" Aug 27 21:22:02 living-room volumio[1233]: info: ------------------------------ 21ms Aug 27 21:22:02 living-room volumio[1233]: info: camilladsp respawn in 1600 ms (attempt 5/10) Aug 27 21:22:02 living-room volumio[1233]: info: getStreamUrl took 259 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand stop took 0 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 21:22:02 living-room volumio[1233]: info: Aug 27 21:22:02 living-room volumio[1233]: ---------------------------- MPD announces system playlist update Aug 27 21:22:02 living-room volumio[1233]: info: Ignoring MPD Status Update Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand clear took 1 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NzE0N2M0ZWI1ODdiODg4NDJkNjc3YjM2YjY5MTUyMV82MS5tcDQ/0.flac?token=1787887322~ODU2Nzk5YTkxYjg5YzAzYjg2MjY4ZjA3ODU3ZmNlYmY4YzgzMjU2Mg==" Aug 27 21:22:02 living-room volumio[1233]: error: updateQueue error: null Aug 27 21:22:02 living-room volumio[1233]: info: Aug 27 21:22:02 living-room volumio[1233]: ---------------------------- MPD announces system playlist update Aug 27 21:22:02 living-room volumio[1233]: info: Ignoring MPD Status Update Aug 27 21:22:02 living-room volumio[1233]: info: ------------------------------ 3ms Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NzE0N2M0ZWI1ODdiODg4NDJkNjc3YjM2YjY5MTUyMV82MS5tcDQ/0.flac?token=1787887322~ODU2Nzk5YTkxYjg5YzAzYjg2MjY4ZjA3ODU3ZmNlYmY4YzgzMjU2Mg==" took 2 milliseconds Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Aug 27 21:22:02 living-room volumio[1233]: info: ------------------------------ 2ms Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand play took 2 milliseconds Aug 27 21:22:02 living-room volumio[1233]: info: Aug 27 21:22:02 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:02 living-room volumio[1233]: info: Aug 27 21:22:02 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand status took 2 milliseconds Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand status took 1 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 21:22:02 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:02 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1787887322~ODU2Nzk5YTkxYjg5YzAzYjg2MjY4ZjA3ODU3ZmNlYmY4YzgzMjU2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NzE0N2M0ZWI1ODdiODg4NDJkNjc3YjM2YjY5MTUyMV82MS5tcDQ/0.flac?token=1787887322~ODU2Nzk5YTkxYjg5YzAzYjg2MjY4ZjA3ODU3ZmNlYmY4YzgzMjU2Mg==","trackType":"tidal"} Aug 27 21:22:02 living-room volumio[1233]: verbose: CURRENT POSITION 0 Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::syncState stateService play Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 27 21:22:02 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:02 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:02 living-room volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1787887322~ODU2Nzk5YTkxYjg5YzAzYjg2MjY4ZjA3ODU3ZmNlYmY4YzgzMjU2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NzE0N2M0ZWI1ODdiODg4NDJkNjc3YjM2YjY5MTUyMV82MS5tcDQ/0.flac?token=1787887322~ODU2Nzk5YTkxYjg5YzAzYjg2MjY4ZjA3ODU3ZmNlYmY4YzgzMjU2Mg==","trackType":"tidal"} Aug 27 21:22:02 living-room volumio[1233]: verbose: CURRENT POSITION 0 Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::syncState stateService play Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::syncState currentStatus play Aug 27 21:22:02 living-room volumio[1233]: info: Received an update from plugin. extracting info from payload Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:02 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:02 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:02 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:02 living-room volumio[1233]: info: ------------------------------ 40ms Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.455-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.455-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/100202039 title="Hotel California" Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.458-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 27 21:22:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:02.459-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/100202039 title="Hotel California" Aug 27 21:22:02 living-room volumio[1233]: info: ------------------------------ 106ms Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - Volumio is playing Aug 27 21:22:02 living-room volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:22:02 living-room volumio[1233]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - ---- read samplerate from file: 384000 Aug 27 21:22:02 living-room volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 27 21:22:02 living-room volumio[1233]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 27 21:22:06 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::ClearQueue Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::stop Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::stPlaybackTimer Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::serviceStop Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::serviceStop Aug 27 21:22:06 living-room volumio[1233]: info: [1787883726455] ControllerTidal::stop Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::stop Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::clearPlayQueue Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::addQueueItems Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::addQueueItems Aug 27 21:22:06 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:06 living-room volumio[1233]: info: Adding Item to queue: tidal://song/100202039 Aug 27 21:22:06 living-room volumio[1233]: info: Using cached record of: tidal://song/100202039 Aug 27 21:22:06 living-room volumio[1233]: info: Adding Item to queue: tidal://song/20767339 Aug 27 21:22:06 living-room volumio[1233]: info: Using cached record of: tidal://song/20767339 Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.461-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.463-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/100202039 title="Hotel California" Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPlay Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::play index 1 Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::addQueueItems Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::addQueueItems Aug 27 21:22:06 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:06 living-room volumio[1233]: info: Adding Item to queue: tidal://song/20750059 Aug 27 21:22:06 living-room volumio[1233]: info: Using cached record of: tidal://song/20750059 Aug 27 21:22:06 living-room volumio[1233]: info: Adding Item to queue: tidal://song/96854648 Aug 27 21:22:06 living-room volumio[1233]: info: Using cached record of: tidal://song/96854648 Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::stop Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::play index undefined Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: info: [1787883726490] ControllerTidal::clearAddPlayTrack Aug 27 21:22:06 living-room volumio[1233]: info: Getting stream with soundQuality HI_RES Aug 27 21:22:06 living-room volumio[1233]: info: FusionDsp - Volumio is not playing Aug 27 21:22:06 living-room volumio[1233]: info: FusionDsp - Clipped samples monitor stopped Aug 27 21:22:06 living-room volumio[1233]: info: Aug 27 21:22:06 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand stop took 51 milliseconds Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:06 living-room volumio[1233]: info: camilladsp respawn in 3200 ms (attempt 6/10) Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand status took 4 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.525-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_STOPPED positionMs=3 volume=100 Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.525-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:06 living-room volumio[1233]: info: ------------------------------ 25ms Aug 27 21:22:06 living-room volumio[1233]: info: getStreamUrl took 93 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand stop took 1 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 21:22:06 living-room volumio[1233]: info: Aug 27 21:22:06 living-room volumio[1233]: ---------------------------- MPD announces system playlist update Aug 27 21:22:06 living-room volumio[1233]: info: Ignoring MPD Status Update Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand clear took 1 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887326~ZjU1MDY2Y2QxODVlMmQxOWY5ZjExN2NlZjkwODJmN2UyMGQwOTRhZg==" Aug 27 21:22:06 living-room volumio[1233]: error: updateQueue error: null Aug 27 21:22:06 living-room volumio[1233]: info: Aug 27 21:22:06 living-room volumio[1233]: ---------------------------- MPD announces system playlist update Aug 27 21:22:06 living-room volumio[1233]: info: Ignoring MPD Status Update Aug 27 21:22:06 living-room volumio[1233]: info: ------------------------------ 2ms Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887326~ZjU1MDY2Y2QxODVlMmQxOWY5ZjExN2NlZjkwODJmN2UyMGQwOTRhZg==" took 1 milliseconds Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Aug 27 21:22:06 living-room volumio[1233]: info: ------------------------------ 1ms Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand play took 2 milliseconds Aug 27 21:22:06 living-room volumio[1233]: info: Aug 27 21:22:06 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:06 living-room volumio[1233]: info: Aug 27 21:22:06 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand status took 43 milliseconds Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand status took 3 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 27 21:22:06 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:06 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"0.flac?token=1787887326~ZjU1MDY2Y2QxODVlMmQxOWY5ZjExN2NlZjkwODJmN2UyMGQwOTRhZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887326~ZjU1MDY2Y2QxODVlMmQxOWY5ZjExN2NlZjkwODJmN2UyMGQwOTRhZg==","trackType":"tidal"} Aug 27 21:22:06 living-room volumio[1233]: verbose: CURRENT POSITION 1 Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::syncState stateService play Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 27 21:22:06 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:06 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:06 living-room volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"729 Kbps","isStreaming":false,"title":"0.flac?token=1787887326~ZjU1MDY2Y2QxODVlMmQxOWY5ZjExN2NlZjkwODJmN2UyMGQwOTRhZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887326~ZjU1MDY2Y2QxODVlMmQxOWY5ZjExN2NlZjkwODJmN2UyMGQwOTRhZg==","trackType":"tidal"} Aug 27 21:22:06 living-room volumio[1233]: verbose: CURRENT POSITION 1 Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::syncState stateService play Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::syncState currentStatus play Aug 27 21:22:06 living-room volumio[1233]: info: Received an update from plugin. extracting info from payload Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:06 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:06 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:06 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:06 living-room volumio[1233]: info: ------------------------------ 156ms Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.793-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_PLAYING positionMs=114 volume=100 Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.793-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:06 living-room volumio[1233]: info: ------------------------------ 164ms Aug 27 21:22:06 living-room volumio[1233]: info: FusionDsp - Volumio is playing Aug 27 21:22:06 living-room volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.824-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_PLAYING positionMs=114 volume=100 Aug 27 21:22:06 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:06.826-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:06 living-room volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Aug 27 21:22:06 living-room volumio[1233]: info: FusionDsp - ---- read samplerate from file: 384000 Aug 27 21:22:06 living-room volumio[1233]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:06 living-room volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms Aug 27 21:22:07 living-room volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 27 21:22:07 living-room volumio[1233]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 27 21:22:09 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::ClearQueue Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::stop Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::stPlaybackTimer Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::serviceStop Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::serviceStop Aug 27 21:22:09 living-room volumio[1233]: info: [1787883729674] ControllerTidal::stop Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::stop Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::clearPlayQueue Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::addQueueItems Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::addQueueItems Aug 27 21:22:09 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:09 living-room volumio[1233]: info: Adding Item to queue: tidal://song/100202039 Aug 27 21:22:09 living-room volumio[1233]: info: Using cached record of: tidal://song/100202039 Aug 27 21:22:09 living-room volumio[1233]: info: Adding Item to queue: tidal://song/20767339 Aug 27 21:22:09 living-room volumio[1233]: info: Using cached record of: tidal://song/20767339 Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.679-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.679-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPlay Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::play index 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::addQueueItems Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::addQueueItems Aug 27 21:22:09 living-room volumio[1233]: info: Preload queue cleared Aug 27 21:22:09 living-room volumio[1233]: info: Adding Item to queue: tidal://song/20750059 Aug 27 21:22:09 living-room volumio[1233]: info: Using cached record of: tidal://song/20750059 Aug 27 21:22:09 living-room volumio[1233]: info: Adding Item to queue: tidal://song/96854648 Aug 27 21:22:09 living-room volumio[1233]: info: Using cached record of: tidal://song/96854648 Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::stop Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::saveQueue Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::play index undefined Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::updateTrackBlock Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrackBlock Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: [1787883729704] ControllerTidal::clearAddPlayTrack Aug 27 21:22:09 living-room volumio[1233]: info: Getting stream with soundQuality HI_RES Aug 27 21:22:09 living-room volumio[1233]: info: FusionDsp - Volumio is not playing Aug 27 21:22:09 living-room volumio[1233]: info: FusionDsp - Clipped samples monitor stopped Aug 27 21:22:09 living-room volumio[1233]: info: Aug 27 21:22:09 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand stop took 47 milliseconds Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand status took 8 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.748-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.748-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:09 living-room volumio[1233]: info: ------------------------------ 34ms Aug 27 21:22:09 living-room volumio[1233]: info: getStreamUrl took 93 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand stop took 0 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 21:22:09 living-room volumio[1233]: info: Aug 27 21:22:09 living-room volumio[1233]: ---------------------------- MPD announces system playlist update Aug 27 21:22:09 living-room volumio[1233]: info: Ignoring MPD Status Update Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand clear took 1 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887329~MjdmOWZlMzI3MWRjZThjNWU2Mjk5MTFhMzk1MmJiY2NjMjc5MTZmYg==" Aug 27 21:22:09 living-room volumio[1233]: error: updateQueue error: null Aug 27 21:22:09 living-room volumio[1233]: info: ------------------------------ 1ms Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887329~MjdmOWZlMzI3MWRjZThjNWU2Mjk5MTFhMzk1MmJiY2NjMjc5MTZmYg==" took 1 milliseconds Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Aug 27 21:22:09 living-room volumio[1233]: info: Aug 27 21:22:09 living-room volumio[1233]: ---------------------------- MPD announces system playlist update Aug 27 21:22:09 living-room volumio[1233]: info: Ignoring MPD Status Update Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand play took 3 milliseconds Aug 27 21:22:09 living-room volumio[1233]: info: ------------------------------ 3ms Aug 27 21:22:09 living-room volumio[1233]: info: Aug 27 21:22:09 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:09 living-room volumio[1233]: info: Aug 27 21:22:09 living-room volumio[1233]: ---------------------------- MPD announces state update: player Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand status took 5 milliseconds Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::getState Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand status took 17 milliseconds Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::parseState Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1787887329~MjdmOWZlMzI3MWRjZThjNWU2Mjk5MTFhMzk1MmJiY2NjMjc5MTZmYg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887329~MjdmOWZlMzI3MWRjZThjNWU2Mjk5MTFhMzk1MmJiY2NjMjc5MTZmYg==","trackType":"tidal"} Aug 27 21:22:09 living-room volumio[1233]: verbose: CURRENT POSITION 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::syncState stateService play Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 27 21:22:09 living-room volumio[1233]: info: ------------------------------ 26ms Aug 27 21:22:09 living-room volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 27 21:22:09 living-room volumio[1233]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:22:09 living-room volumio[1233]: info: ControllerMpd::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::servicePushState Aug 27 21:22:09 living-room volumio[1233]: info: CorePlayQueue::getTrack 1 Aug 27 21:22:09 living-room volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"0.flac?token=1787887329~MjdmOWZlMzI3MWRjZThjNWU2Mjk5MTFhMzk1MmJiY2NjMjc5MTZmYg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3N2E3ODU0MjZjOTljOWMzMjUxNzIzYTg1ODg0YjI2YV82MS5tcDQ/0.flac?token=1787887329~MjdmOWZlMzI3MWRjZThjNWU2Mjk5MTFhMzk1MmJiY2NjMjc5MTZmYg==","trackType":"tidal"} Aug 27 21:22:09 living-room volumio[1233]: verbose: CURRENT POSITION 1 Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::syncState stateService play Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::syncState currentStatus play Aug 27 21:22:09 living-room volumio[1233]: info: Received an update from plugin. extracting info from payload Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:09 living-room volumio[1233]: info: CoreStateMachine::pushState Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:22:09 living-room volumio[1233]: info: MRS: Pushing multiroomSync output Aug 27 21:22:09 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.897-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.898-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.904-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 27 21:22:09 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:09.904-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" id=tidal://song/20767339 title="Hotel California (2013 Remaster)" Aug 27 21:22:09 living-room volumio[1233]: info: ------------------------------ 119ms Aug 27 21:22:09 living-room volumio[1233]: info: FusionDsp - Volumio is playing Aug 27 21:22:09 living-room volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:22:09 living-room volumio[1233]: info: FusionDsp - Clipping Monitor started Aug 27 21:22:10 living-room volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Aug 27 21:22:10 living-room volumio[1233]: info: FusionDsp - ---- read samplerate from file: 384000 Aug 27 21:22:10 living-room volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 27 21:22:18 living-room bluealsa[965]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_57_1A_53_37_06_18, ...) Aug 27 21:22:28 living-room bluealsa[965]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_57_1A_53_37_06_18, ...) Aug 27 21:22:29 living-room volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:22:29 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 21:22:29 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 27 21:22:29 living-room sudo[6776]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 27 21:22:29 living-room sudo[6776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:29 living-room sudo[6782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:22:29 living-room sudo[6782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:29 living-room sudo[6787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:22:29 living-room sudo[6787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:29 living-room sudo[6792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:22:29 living-room sudo[6792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:29 living-room sudo[6787]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:29 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 21:22:29 living-room sudo[6782]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:29 living-room sudo[6776]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:29 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 21:22:29 living-room sudo[6792]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:29 living-room sudo[6799]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:22:29 living-room sudo[6799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:29 living-room sudo[6799]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:29 living-room sudo[6802]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:29 living-room sudo[6802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:29 living-room sudo[6802]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:29 living-room sudo[6807]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 21:22:29 living-room sudo[6807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:32 living-room sudo[6807]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:32 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:32.840-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:22:32 living-room volumio[1233]: info: Received Get System Info Aug 27 21:22:32 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:32 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:32 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:32 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:32 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:32 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:32 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:33 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:33.734-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:22:35 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:35 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:35 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:35 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:35 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:35 living-room volumio[1233]: verbose: New Socket.io Connection to 192.168.6.244:3000 from 192.168.5.250 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 27 21:22:35 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:35 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:42 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Aug 27 21:22:42 living-room volumio[1233]: info: Saving new wireless network Aug 27 21:22:42 living-room sudo[6843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Aug 27 21:22:42 living-room sudo[6843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:42 living-room sudo[6843]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:42 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 27 21:22:42 living-room sudo[6846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 27 21:22:42 living-room sudo[6846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:42 living-room systemd[1]: Stopping wireless.service - Wireless Services... Aug 27 21:22:42 living-room systemd[1]: wireless.service: Killing process 1215 (wpa_supplicant) with signal SIGKILL. Aug 27 21:22:42 living-room systemd[1]: wireless.service: Deactivated successfully. Aug 27 21:22:42 living-room systemd[1]: Stopped wireless.service - Wireless Services. Aug 27 21:22:42 living-room systemd[1]: wireless.service: Consumed 8min 46.315s CPU time. Aug 27 21:22:43 living-room systemd[1]: Starting wireless.service - Wireless Services... Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: === SNM TRANSITION === Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: New ethernet state: connected Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: Single Network Mode: enabled Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: First start: yes Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: === END TRANSITION === Aug 27 21:22:43 living-room wireless.js[6852]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 27 21:22:43 living-room sudo[6876]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 21:22:43 living-room sudo[6877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:43 living-room sudo[6877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:43 living-room sudo[6876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:22:43 living-room sudo[6877]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:43 living-room sudo[6876]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:43 living-room sudo[6880]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 27 21:22:43 living-room sudo[6880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:22:44 living-room sudo[6880]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:44 living-room wireless.js[6852]: WIRELESS.JS - INFO: Cleaning previous... Aug 27 21:22:44 living-room sudo[6884]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 27 21:22:44 living-room sudo[6884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:22:44 living-room sudo[6884]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:44 living-room kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 27 21:22:44 living-room wireless.js[6852]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 27 21:22:44 living-room wireless.js[6852]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Aug 27 21:22:44 living-room wireless.js[6852]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: dc:a6:32:ce:0f:0a) Aug 27 21:22:44 living-room sudo[6891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 27 21:22:44 living-room sudo[6891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:44 living-room sudo[6891]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:44 living-room sudo[6899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 27 21:22:44 living-room sudo[6899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:44 living-room sudo[6904]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:44 living-room sudo[6904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:44 living-room sudo[6904]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:44 living-room volumio[1233]: info: Received Get System Info Aug 27 21:22:44 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:44 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:44 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:44 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:44 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:44 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:44.723-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:22:44 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:44 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:45 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:45.508-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:22:45 living-room sudo[6907]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:45 living-room sudo[6907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:45 living-room sudo[6907]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:46 living-room sudo[6910]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:46 living-room sudo[6910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:46 living-room sudo[6910]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:47 living-room sudo[6899]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: Regdomain already correct: US Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Aug 27 21:22:47 living-room sudo[6917]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 27 21:22:47 living-room sudo[6917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:22:47 living-room sudo[6917]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:47 living-room sudo[6920]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 21:22:47 living-room sudo[6920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:22:47 living-room sudo[6920]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:47 living-room wpa_supplicant[6923]: Successfully initialized wpa_supplicant Aug 27 21:22:47 living-room wpa_supplicant[6923]: nl80211: kernel reports: Registration to specific type not supported Aug 27 21:22:47 living-room wpa_supplicant[6926]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 4091ms Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: wlan0 state: wpa_state=SCANNING (expected DISCONNECTED or INACTIVE) Aug 27 21:22:47 living-room wireless.js[6852]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 27 21:22:47 living-room systemd[1]: Started wireless.service - Wireless Services. Aug 27 21:22:47 living-room kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 27 21:22:47 living-room sudo[6946]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:47 living-room sudo[6946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:47 living-room sudo[6846]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:47 living-room sudo[6946]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:47 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:47.696-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:22:47 living-room volumio[1233]: info: Received Get System Info Aug 27 21:22:47 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:47 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:47 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:47 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:47 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:47 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:47 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:48 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:48.483-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:22:48 living-room sudo[6950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:48 living-room sudo[6950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:48 living-room sudo[6950]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:49 living-room sudo[6953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:49 living-room sudo[6953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:49 living-room sudo[6953]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:50 living-room sudo[6970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:50 living-room sudo[6970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:50 living-room sudo[6970]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:50 living-room volumio[1233]: info: Received Get System Info Aug 27 21:22:50 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:50 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:50 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:50 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:50 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:50 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:50.866-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:22:50 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:50 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:51 living-room sudo[6973]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:51 living-room sudo[6973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:51 living-room sudo[6973]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:51 living-room volumio5-onboarding[1541]: time=2026-08-27T21:22:51.656-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:22:52 living-room volumio[1233]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Aug 27 21:22:52 living-room volumio[1233]: info: Discovery: Restarting Advertising Aug 27 21:22:52 living-room volumio[1233]: info: Discovery: Stopping existing advertisement Aug 27 21:22:52 living-room volumio[1233]: info: Discovery: Restarting Browsing Aug 27 21:22:52 living-room sudo[6976]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:52 living-room sudo[6976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:52 living-room sudo[6976]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:52 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 27 21:22:52 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 27 21:22:52 living-room sudo[6979]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:52 living-room sudo[6979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:52 living-room sudo[6979]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:53 living-room volumio[1233]: info: Discovery: A device disappeared from network Aug 27 21:22:53 living-room volumio[1233]: info: Discovery: A device disappeared from network Aug 27 21:22:53 living-room sudo[6982]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:53 living-room sudo[6982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:53 living-room sudo[6982]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:54 living-room sudo[6985]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:54 living-room sudo[6985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:54 living-room sudo[6985]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:55 living-room sudo[6988]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:55 living-room sudo[6988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:55 living-room sudo[6988]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:56 living-room sudo[6991]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:56 living-room sudo[6991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:56 living-room sudo[6991]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room volumio[1233]: info: Discovery: Started advertising with name: Living Room Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 27 21:22:57 living-room sudo[6996]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:57 living-room sudo[6996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 27 21:22:57 living-room volumio[1233]: info: Refreshing Cached IP Addresses Aug 27 21:22:57 living-room sudo[6996]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room sudo[6999]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 27 21:22:57 living-room sudo[6999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:57 living-room sudo[7001]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:22:57 living-room sudo[6999]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room sudo[7001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:57 living-room sudo[7004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:57 living-room sudo[7001]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room sudo[7004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:57 living-room sudo[7004]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room sudo[7008]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:22:57 living-room sudo[7008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:57 living-room sudo[7008]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room sudo[7010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:57 living-room sudo[7010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:57 living-room sudo[7010]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:57 living-room volumio[1233]: verbose: New Socket.io Connection to 192.168.6.244 from 192.168.5.250 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 21:22:57 living-room volumio[1233]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 27 21:22:57 living-room volumio[1233]: info: Received Get System Info Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:57 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:57 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:58 living-room volumio[1233]: info: Listing playlists Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 27 21:22:58 living-room sudo[7018]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 27 21:22:58 living-room sudo[7018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7018]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room sudo[7024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:22:58 living-room sudo[7024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7024]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room sudo[7036]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:22:58 living-room sudo[7036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:22:58 living-room sudo[7030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7036]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 21:22:58 living-room sudo[7030]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room sudo[7042]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:22:58 living-room sudo[7042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:58 living-room sudo[7042]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room sudo[7046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7048]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 21:22:58 living-room sudo[7048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7046]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:58 living-room volumio[1233]: verbose: New Socket.io Connection to 192.168.6.244:3000 from 192.168.5.250 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: adding 530e3c22-ee67-47cd-90e4-9ef5a5bbb58c Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: Found device Living Room Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:58 living-room sudo[7057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:58 living-room sudo[7057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:58 living-room sudo[7057]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: this is already registered, 530e3c22-ee67-47cd-90e4-9ef5a5bbb58c Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: Found device Living Room Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:58 living-room volumio[1233]: verbose: New Socket.io Connection to 192.168.6.244:3000 from 192.168.5.250 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: this is already registered, 530e3c22-ee67-47cd-90e4-9ef5a5bbb58c Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: Found device Living Room Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: this is already registered, 530e3c22-ee67-47cd-90e4-9ef5a5bbb58c Aug 27 21:22:58 living-room volumio[1233]: info: Discovery: Found device Living Room Aug 27 21:22:58 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:59 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 27 21:22:59 living-room sudo[7060]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:22:59 living-room sudo[7060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:22:59 living-room sudo[7060]: pam_unix(sudo:session): session closed for user root Aug 27 21:22:59 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:22:59 living-room volumio[1233]: info: Received Get System Info Aug 27 21:22:59 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:22:59 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:22:59 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:22:59 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:22:59 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:23:00 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:23:00 living-room volumio[1233]: info: Received Get System Info Aug 27 21:23:00 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:23:00 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:23:00 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:23:00 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:00 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:23:00 living-room sudo[7077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:00 living-room sudo[7077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:00 living-room sudo[7077]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:01 living-room sudo[7048]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:01 living-room sudo[7080]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:01 living-room sudo[7080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:01 living-room sudo[7080]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:01 living-room volumio[1233]: info: Received Get System Info Aug 27 21:23:01 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:23:01 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:23:01 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:23:01 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:01 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:23:01 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:01.728-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:23:01 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:23:01 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:23:02 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:02.654-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:23:02 living-room sudo[7083]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:02 living-room sudo[7083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:02 living-room sudo[7083]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:03 living-room sudo[7086]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:03 living-room sudo[7086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:03 living-room sudo[7086]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:04 living-room sudo[7090]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:04 living-room sudo[7090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:04 living-room sudo[7090]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:05 living-room sudo[7093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:05 living-room sudo[7093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:05 living-room sudo[7093]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:06 living-room sudo[7096]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:06 living-room sudo[7096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:06 living-room sudo[7096]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:07 living-room sudo[7099]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:07 living-room sudo[7099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:07 living-room sudo[7099]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:07 living-room sudo[7102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:23:07 living-room sudo[7102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:07 living-room sudo[7102]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:07 living-room sudo[7104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:07 living-room sudo[7104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:07 living-room sudo[7104]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:07 living-room sudo[7108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 21:23:07 living-room sudo[7108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:07 living-room sudo[7108]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:07 living-room volumio[1233]: info: Upmpdcli Daemon Started Aug 27 21:23:07 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 21:23:08 living-room sudo[7111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:08 living-room sudo[7111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:08 living-room sudo[7111]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:09 living-room sudo[7114]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:09 living-room sudo[7114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:09 living-room sudo[7114]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:10 living-room sudo[7131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:10 living-room sudo[7131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:10 living-room sudo[7131]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:11 living-room sudo[7134]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:11 living-room sudo[7134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:11 living-room sudo[7134]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:12 living-room sudo[7137]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:23:12 living-room sudo[7137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:12 living-room sudo[7137]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:12 living-room dhcpcd[703]: eth0: carrier lost Aug 27 21:23:12 living-room dhcpcd[860]: eth0: carrier lost Aug 27 21:23:12 living-room kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 27 21:23:12 living-room dhcpcd[703]: eth0: deleting route to 192.168.4.0/22 Aug 27 21:23:12 living-room avahi-daemon[811]: Withdrawing address record for 192.168.6.244 on eth0. Aug 27 21:23:12 living-room avahi-daemon[811]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.6.244. Aug 27 21:23:12 living-room avahi-daemon[811]: Interface eth0.IPv4 no longer relevant for mDNS. Aug 27 21:23:12 living-room dhcpcd[703]: eth0: deleting default route via 192.168.4.1 Aug 27 21:23:12 living-room volumio[1233]: info: Discovery: A device disappeared from network Aug 27 21:23:12 living-room dhcpcd[860]: eth0: deleting route to 192.168.4.0/22 Aug 27 21:23:12 living-room dhcpcd[860]: eth0: deleting default route via 192.168.4.1 Aug 27 21:23:12 living-room volumio[1233]: info: Discovery: A device disappeared from network Aug 27 21:23:12 living-room systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 27 21:23:12 living-room systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Aug 27 21:23:12 living-room systemd[1]: welcome.service: Deactivated successfully. Aug 27 21:23:12 living-room systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 27 21:23:12 living-room systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 27 21:23:12 living-room systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 27 21:23:12 living-room welcome[7190]: Resolved ip:[0] Aug 27 21:23:12 living-room systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 27 21:23:12 living-room systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 27 21:23:13 living-room ifplugd(eth0)[1029]: Link beat lost. Aug 27 21:23:13 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:13.274-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 27 21:23:13 living-room volumio[1233]: info: Received Get System Info Aug 27 21:23:13 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:23:13 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:23:13 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:23:13 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:13 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:23:13 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:23:13 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:23:13 living-room ntpd[1030]: IO: Deleting interface #3 eth0, 192.168.6.244#123, interface stats: received=402, sent=414, dropped=0, active_time=2938 secs Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 136.55.43.142 unlink local addr 192.168.6.244 -> Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 73.185.182.209 unlink local addr 192.168.6.244 -> Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 162.159.200.1 unlink local addr 192.168.6.244 -> Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 69.164.213.136 unlink local addr 192.168.6.244 -> Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 23.143.196.202 unlink local addr 192.168.6.244 -> Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 23.157.100.123 unlink local addr 192.168.6.244 -> Aug 27 21:23:13 living-room ntpd[1030]: PROTO: 198.46.254.130 unlink local addr 192.168.6.244 -> Aug 27 21:23:14 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:14.217-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:23:19 living-room volumio[1233]: info: Volumio Network Manager: Network status updated: 0 Aug 27 21:23:23 living-room ifplugd(eth0)[1029]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Aug 27 21:23:23 living-room dhcpcd[7224]: sending signal ALRM to pid 702 Aug 27 21:23:23 living-room dhcpcd[7224]: waiting for pid 702 to exit Aug 27 21:23:23 living-room ifplugd(eth0)[1029]: client: sending signal ALRM to pid 702 Aug 27 21:23:23 living-room ifplugd(eth0)[1029]: client: waiting for pid 702 to exit Aug 27 21:23:23 living-room dhcpcd[703]: received SIGALRM, releasing Aug 27 21:23:23 living-room dhcpcd[703]: eth0: removing interface Aug 27 21:23:23 living-room dhcpcd[703]: dhcpcd exited Aug 27 21:23:23 living-room ifplugd(eth0)[1029]: Program executed successfully. Aug 27 21:23:23 living-room kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Aug 27 21:23:23 living-room kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 27 21:23:24 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:24.119-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 27 21:23:24 living-room volumio[1233]: info: Received Get System Info Aug 27 21:23:24 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:23:24 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:23:24 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:23:24 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:24 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:23:24 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:23:24 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: === SNM TRANSITION === Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: Previous ethernet state: connected Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: New ethernet state: disconnected Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: Single Network Mode: enabled Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: First start: no Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: Action: Reconnect WiFi Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: === END TRANSITION === Aug 27 21:23:24 living-room sudo[7250]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 21:23:24 living-room sudo[7250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:24 living-room sudo[7250]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Aug 27 21:23:24 living-room wireless.js[6852]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnection triggered Aug 27 21:23:25 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:25.096-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:23:27 living-room wpa_supplicant[6926]: wlan0: Trying to associate with e4:19:7f:c9:55:87 (SSID='jojojohnp' freq=5180 MHz) Aug 27 21:23:27 living-room wpa_supplicant[6926]: wlan0: Associated with e4:19:7f:c9:55:87 Aug 27 21:23:27 living-room wpa_supplicant[6926]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 27 21:23:27 living-room wpa_supplicant[6926]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Aug 27 21:23:27 living-room wpa_supplicant[6926]: wlan0: WPA: Key negotiation completed with e4:19:7f:c9:55:87 [PTK=CCMP GTK=CCMP] Aug 27 21:23:27 living-room wpa_supplicant[6926]: wlan0: CTRL-EVENT-CONNECTED - Connection to e4:19:7f:c9:55:87 completed [id=0 id_str=] Aug 27 21:23:27 living-room dhcpcd[860]: wlan0: carrier acquired Aug 27 21:23:27 living-room dhcpcd[860]: wlan0: connected to Access Point: jojojohnp Aug 27 21:23:27 living-room dhcpcd[860]: wlan0: IAID 32:ce:0f:0a Aug 27 21:23:27 living-room dhcpcd[860]: wlan0: adding address fe80::a2f8:3ab:aed1:a904 Aug 27 21:23:27 living-room dhcpcd[860]: ipv6_addaddr1: Permission denied Aug 27 21:23:27 living-room wireless.js[6852]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnected successfully Aug 27 21:23:27 living-room wireless.js[6852]: WIRELESS.JS - INFO: reconnectWiFi: Using DHCP for IP Aug 27 21:23:27 living-room wireless.js[6852]: WIRELESS.JS - INFO: SNM: WiFi reconnection completed in 3128ms Aug 27 21:23:27 living-room wireless.js[6852]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnection complete, obtaining IP Aug 27 21:23:27 living-room wireless.js[6852]: WIRELESS.JS - INFO: Restarting avahi-daemon... Aug 27 21:23:27 living-room sudo[7267]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Aug 27 21:23:27 living-room sudo[7267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:23:27 living-room dhcpcd[860]: control command: /sbin/dhcpcd wlan0 Aug 27 21:23:27 living-room sudo[7267]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:27 living-room sudo[7272]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Aug 27 21:23:27 living-room sudo[7272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:23:27 living-room dhcpcd[860]: control_free: No such file or directory Aug 27 21:23:27 living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 21:23:27 living-room systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Aug 27 21:23:27 living-room systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 21:23:27 living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 21:23:27 living-room systemd[1]: shairport-sync.service: Consumed 8.588s CPU time. Aug 27 21:23:27 living-room systemd[1]: squeezelite.service: Deactivated successfully. Aug 27 21:23:27 living-room systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Aug 27 21:23:27 living-room avahi-daemon[811]: Got SIGTERM, quitting. Aug 27 21:23:27 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:27.864-05:00 level=WARN msg="disconnected from Avahi daemon, trying to reconnect" component=discovery/localnet error="avahi: Daemon connection failed" Aug 27 21:23:27 living-room avahi-daemon[811]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 27 21:23:27 living-room volumio[1233]: error: Discovery: Advertisement error: Error: dns service error: unknown Aug 27 21:23:27 living-room volumio[1233]: error: Discovery: advertisement error: Error: dns service error: unknown Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Stopping Advertising Immediately Aug 27 21:23:27 living-room vtcs[2064]: [2026-08-27 21:23:27.865] [tisoc] [error] [avahiImpl.cpp:113] avahiClientCallback() AVAHI_CLIENT_S_COLLISION/AVAHI_CLIENT_FAILURE Aug 27 21:23:27 living-room avahi-daemon[811]: avahi-daemon 0.8 exiting. Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Stopping existing advertisement Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 27 21:23:27 living-room systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Restarting Browsing Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Restarting Browsing Aug 27 21:23:27 living-room volumio[1233]: info: Discovery: Restart already pending, ignoring duplicate call Aug 27 21:23:27 living-room volumio[1233]: Cast browser error: Error: dns service error: unknown Aug 27 21:23:27 living-room systemd[1]: avahi-daemon.service: Deactivated successfully. Aug 27 21:23:27 living-room systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 27 21:23:27 living-room dbus-daemon[816]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.39' (uid=0 pid=1541 comm="/usr/bin/volumio5-onboarding") Aug 27 21:23:27 living-room systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 27 21:23:27 living-room avahi-daemon[7281]: Process 811 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Aug 27 21:23:27 living-room avahi-daemon[7281]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Aug 27 21:23:27 living-room avahi-daemon[7281]: Successfully dropped root privileges. Aug 27 21:23:27 living-room avahi-daemon[7281]: avahi-daemon 0.8 starting up. Aug 27 21:23:27 living-room dbus-daemon[816]: [system] Successfully activated service 'org.freedesktop.Avahi' Aug 27 21:23:27 living-room systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 27 21:23:27 living-room avahi-daemon[7281]: Successfully called chroot(). Aug 27 21:23:27 living-room avahi-daemon[7281]: Successfully dropped remaining capabilities. Aug 27 21:23:27 living-room avahi-daemon[7281]: Loading service file /services/volumio.service. Aug 27 21:23:27 living-room avahi-daemon[7281]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 27 21:23:27 living-room avahi-daemon[7281]: New relevant interface lo.IPv4 for mDNS. Aug 27 21:23:27 living-room avahi-daemon[7281]: Network interface enumeration completed. Aug 27 21:23:27 living-room avahi-daemon[7281]: Registering new address record for 127.0.0.1 on lo.IPv4. Aug 27 21:23:27 living-room sudo[7272]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:27 living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 21:23:27 living-room systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Aug 27 21:23:27 living-room systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Aug 27 21:23:28 living-room volumio[1233]: info: Received Get System Info Aug 27 21:23:28 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:23:28 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:23:28 living-room volumio[1233]: info: Discovery: Getting this device information Aug 27 21:23:28 living-room volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:28 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:23:28 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:28.174-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.250:64881 @ 0x1bee3c0" available=true connected=true macAddress=dc:a6:32:ce:0f:0a ip4Address= ip6Address= ssid=jojojohnp Aug 27 21:23:28 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:23:28 living-room volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:23:28 living-room dhcpcd[860]: wlan0: soliciting an IPv6 router Aug 27 21:23:28 living-room avahi-daemon[7281]: Server startup complete. Host name is living-room.local. Local service cookie is 669959604. Aug 27 21:23:29 living-room dhcpcd[860]: wlan0: soliciting a DHCP lease Aug 27 21:23:29 living-room avahi-daemon[7281]: Service "Living Room" (/services/volumio.service) successfully established. Aug 27 21:23:29 living-room volumio5-onboarding[1541]: time=2026-08-27T21:23:29.700-05:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:23:30 living-room dhcpcd[860]: wlan0: offered 192.168.6.252 from 192.168.4.1 Aug 27 21:23:30 living-room dhcpcd[860]: wlan0: probing address 192.168.6.252/22 Aug 27 21:23:32 living-room volumio[1233]: info: Discovery: Restarting Advertising Aug 27 21:23:34 living-room volumio[1233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 21:23:34 living-room volumio[1233]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 27 21:23:34 living-room volumio[1233]: at doSend (node:dgram:717:16) Aug 27 21:23:34 living-room volumio[1233]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Aug 27 21:23:34 living-room volumio[1233]: at afterDns (node:dgram:663:5) Aug 27 21:23:34 living-room volumio[1233]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Aug 27 21:23:34 living-room volumio[1233]: errno: -101, Aug 27 21:23:34 living-room volumio[1233]: code: 'ENETUNREACH', Aug 27 21:23:34 living-room volumio[1233]: syscall: 'send', Aug 27 21:23:34 living-room volumio[1233]: address: '255.255.255.255', Aug 27 21:23:34 living-room volumio[1233]: port: 3483 Aug 27 21:23:34 living-room volumio[1233]: } Aug 27 21:23:34 living-room volumio[1233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 21:23:34 living-room dhcpcd[860]: wlan0: leased 192.168.6.252 for 14400 seconds Aug 27 21:23:34 living-room avahi-daemon[7281]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.6.252. Aug 27 21:23:34 living-room avahi-daemon[7281]: New relevant interface wlan0.IPv4 for mDNS. Aug 27 21:23:34 living-room dhcpcd[860]: wlan0: adding route to 192.168.4.0/22 Aug 27 21:23:34 living-room avahi-daemon[7281]: Registering new address record for 192.168.6.252 on wlan0.IPv4. Aug 27 21:23:34 living-room dhcpcd[860]: wlan0: adding default route via 192.168.4.1 Aug 27 21:23:34 living-room systemd[1]: welcome.service: Deactivated successfully. Aug 27 21:23:34 living-room systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 27 21:23:34 living-room systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 27 21:23:34 living-room systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 27 21:23:34 living-room welcome[7346]: Resolved ip:[1] 192.168.6.252 Aug 27 21:23:34 living-room systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 27 21:23:34 living-room systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 27 21:23:34 living-room sudo[7366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 21:22' Aug 27 21:23:34 living-room sudo[7366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"