-- Logs begin at Thu 2026-03-26 09:57:25 EDT, end at Thu 2026-03-26 20:32:31 EDT. -- Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.738-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=436.920253ms timeout=3s volume=1 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:26 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:26 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.791-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=113688 volume=1 Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.791-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.815-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.006227ms timeout=3s volume=2 Mar 26 20:32:26 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:26 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:26 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:26 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.856-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=113986 volume=2 Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.856-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:26 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:26 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.864-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=435.635731ms timeout=3s volume=3 Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.870-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=113986 volume=1 Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.870-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:26 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:26 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:26 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:26 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:26 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:26 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.895-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=113986 volume=3 Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.896-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:26 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:26 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.965-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.795556ms timeout=3s volume=4 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:26 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:26 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.973-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=113986 volume=4 Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.973-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:26 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:26 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.989-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=431.413318ms timeout=3s volume=5 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:26 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:26 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:26 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:26 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.999-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=113986 volume=5 Mar 26 20:32:26 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:26.999-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.054-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=432.26186ms timeout=3s volume=6 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.061-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=6 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.062-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.160-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=442.976924ms timeout=3s volume=7 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.168-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=7 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.169-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.182-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=448.458918ms timeout=3s volume=8 Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.192-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=2 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.192-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.205-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=8 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.205-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.207-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=446.526001ms timeout=3s volume=9 Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.230-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=9 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.231-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.259-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=436.065563ms timeout=3s volume=9 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.260-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=3 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.261-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.261-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=4 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.261-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.262-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=5 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.262-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.263-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=6 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.263-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.299-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114235 volume=9 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.300-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.389-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=433.317493ms timeout=3s volume=7 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.395-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114495 volume=7 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.396-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.646-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114745 volume=7 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.646-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.660-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114745 volume=9 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.660-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.684-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114745 volume=9 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.685-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:27 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:27 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:27 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.697-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=114745 volume=7 Mar 26 20:32:27 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:27.697-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:27 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.544-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=440.497762ms timeout=3s volume=8 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.560-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=116515 volume=8 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.561-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.648-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=116771 volume=8 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.648-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.781-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.678006ms timeout=3s volume=9 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.794-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=116771 volume=9 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.795-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.845-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.718869ms timeout=3s volume=10 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.857-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.857-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.895-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=438.232634ms timeout=3s volume=11 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.905-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.905-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.929-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.682841ms timeout=3s volume=12 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.936-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.937-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.948-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=9 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.948-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.960-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.960-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:29 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:29 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:29 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:29 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.981-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:29 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:29.981-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:29 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:29 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.014-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=431.6496ms timeout=3s volume=11 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.021-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.021-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.031-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.804806ms timeout=3s volume=12 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.037-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117021 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.037-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.088-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=432.313196ms timeout=3s volume=13 Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.091-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.092-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.098-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.098-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.112-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.112-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.125-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.126-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.150-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.637ms timeout=3s volume=11 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.158-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.158-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.187-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.188-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.193-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=431.244619ms timeout=3s volume=12 Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.201-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.201-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.225-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.226-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.257-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=429.220927ms timeout=3s volume=13 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.264-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.264-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.296-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.296-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.297-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=439.267068ms timeout=3s volume=14 Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.302-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.303-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.318-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117275 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.318-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.347-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.231051ms timeout=3s volume=15 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.354-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.355-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.368-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.368-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.398-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.780983ms timeout=3s volume=11 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.405-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.405-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.435-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.435-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.438-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.184759ms timeout=3s volume=12 Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.450-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.450-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.460-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.460-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.498-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.498-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.527-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=429.317242ms timeout=3s volume=13 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.535-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.535-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.547-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=432.56531ms timeout=3s volume=14 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.553-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.553-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.584-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117527 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.584-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.587-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=428.95529ms timeout=3s volume=15 Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.596-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.597-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.609-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.609-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.644-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=431.45245ms timeout=3s volume=11 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.651-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.651-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.688-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.403817ms timeout=3s volume=12 Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.691-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.691-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.704-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.704-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.716-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.717-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.743-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=429.711458ms timeout=3s volume=13 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.750-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.750-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: CoreStateMachine::pushState Mar 26 20:32:30 drs1 volumio[803]: info: CorePlayQueue::getTrack 3098 Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:32:30 drs1 volumio[803]: info: CoreCommandRouter::volumioPushState Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output update for this device Mar 26 20:32:30 drs1 volumio[803]: info: MRS: Pushing multiroomSync output Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.779-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" state=STATUS_PLAYING positionMs=117780 volume=10 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.779-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" id="mnt/USB/SanDisk/MUSIC-MP3/CANDLEBOX/hits/11 Track 11.mp3" title="Track 11" Mar 26 20:32:30 drs1 volumio[803]: info: Signalling Playback active due to playback status change Mar 26 20:32:30 drs1 volumio[803]: info: Updating RAAT Signal Path Mar 26 20:32:30 drs1 volumio[803]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:32:30 drs1 volumio[803]: TypeError: Cannot read property 'slice' of null Mar 26 20:32:30 drs1 volumio[803]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 26 20:32:30 drs1 volumio[803]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 26 20:32:30 drs1 volumio[803]: at Object.onceWrapper (events.js:422:26) Mar 26 20:32:30 drs1 volumio[803]: at ClientRequest.emit (events.js:315:20) Mar 26 20:32:30 drs1 volumio[803]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 26 20:32:30 drs1 volumio[803]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 26 20:32:30 drs1 volumio[803]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 26 20:32:30 drs1 volumio[803]: at TLSSocket.emit (events.js:315:20) Mar 26 20:32:30 drs1 volumio[803]: at addChunk (internal/streams/readable.js:309:12) Mar 26 20:32:30 drs1 volumio[803]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 26 20:32:30 drs1 volumio[803]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 26 20:32:30 drs1 volumio[803]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 26 20:32:30 drs1 volumio[803]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.804-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.45333ms timeout=3s volume=14 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.840-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=431.355321ms timeout=3s volume=15 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.891-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=429.141223ms timeout=3s volume=16 Mar 26 20:32:30 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:30.949-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=429.180545ms timeout=3s volume=17 Mar 26 20:32:31 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:31.015-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=449.383056ms timeout=3s volume=18 Mar 26 20:32:31 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:31.049-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=437.462822ms timeout=3s volume=19 Mar 26 20:32:31 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:31.100-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=435.973485ms timeout=3s volume=20 Mar 26 20:32:31 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:31.165-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=439.60152ms timeout=3s volume=21 Mar 26 20:32:31 drs1 sudo[7595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-26 20:31 Mar 26 20:32:31 drs1 sudo[7595]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:32:31 drs1 volumio5-onboarding[1125]: time=2026-03-26T20:32:31.197-04:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.254.158:36118,00:00:00:00:00:00%03 @ 0x28a8030" latency=430.781505ms timeout=3s volume=22 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="emotivadrs1" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 12:03:30 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Emotiva DRS-1" VOLUMIO_VENDOR="Emotiva" VOLUMIO_MODEL="DRS-1" VOLUMIO_HASH="5655ad3ef69f605969779bb6dab18482"