May 25 21:41:21 volumio volumio5-onboarding[1396]: time=2026-05-25T21:41:21.455-05:00 level=INFO msg="player play request" component=server type=REQUEST_TYPE_PLAYER_PLAY peer="00:00:00:00:00:00%b5,192.168.1.2:62663 @ 0x3078420" latency=37.572631ms timeout=10s May 25 21:41:21 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 25 21:41:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay May 25 21:41:21 volumio volumio[1204]: info: CoreStateMachine::play index undefined May 25 21:41:21 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:41:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:39 volumio volumio5-onboarding[1396]: time=2026-05-25T21:41:39.960-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%b5,192.168.1.2:62663 @ 0x3078420" latency=38.744085ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 25 21:41:40 volumio sudo[18210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 21:41:40 volumio sudo[18210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:41:40 volumio sudo[18210]: pam_unix(sudo:session): session closed for user root May 25 21:41:40 volumio sudo[18212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 21:41:40 volumio sudo[18212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:41:40 volumio sudo[18212]: pam_unix(sudo:session): session closed for user root May 25 21:41:40 volumio volumio[1204]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.2 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 May 25 21:41:40 volumio sudo[18216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 21:41:40 volumio sudo[18216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:41:40 volumio sudo[18216]: pam_unix(sudo:session): session closed for user root May 25 21:41:40 volumio sudo[18218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 21:41:40 volumio sudo[18218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:41:40 volumio sudo[18218]: pam_unix(sudo:session): session closed for user root May 25 21:41:40 volumio volumio[1204]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.2 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 May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:40 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 21:41:40 volumio volumio[1204]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 25 21:41:40 volumio volumio[1204]: info: Received Get System Info May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:41:40 volumio volumio[1204]: info: Discovery: Getting this device information May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:40 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:40 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:40 volumio volumio[1204]: info: Listing playlists May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 21:41:40 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 21:41:42 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 21:41:42 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 21:41:42 volumio volumio[1204]: info: Received Get System Info May 25 21:41:42 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:41:42 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:41:42 volumio volumio[1204]: info: Discovery: Getting this device information May 25 21:41:42 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:42 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:42 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:41:43 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 21:41:43 volumio volumio[1204]: info: Received Get System Info May 25 21:41:43 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:41:43 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:41:43 volumio volumio[1204]: info: Discovery: Getting this device information May 25 21:41:43 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:43 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:43 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:41:50 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 21:41:59 volumio volumio[1204]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 21:41:59 volumio volumio[1204]: info: Received Get System Version May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 21:41:59 volumio volumio[1204]: info: Received Get System Info May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:41:59 volumio volumio[1204]: info: Discovery: Getting this device information May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:41:59 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:41:59 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:42:24 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput May 25 21:42:24 volumio volumio[1204]: info: MRS: Setting this device as single May 25 21:42:24 volumio volumio[1204]: info: May 25 21:42:24 volumio volumio[1204]: [1779763344483] ---------------------------- Setting Multiroom Single May 25 21:42:24 volumio volumio[1204]: info: MRS: disable multiroom output May 25 21:42:24 volumio volumio[1204]: info: MRS: Set multiroom target PCM to volumioDiscard May 25 21:42:24 volumio volumio[1204]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard May 25 21:42:25 volumio volumio[1204]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 25 21:42:25 volumio volumio[1204]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback May 25 21:42:25 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT May 25 21:42:25 volumio systemd[1]: mpd.service: Failed with result 'signal'. May 25 21:42:25 volumio systemd[1]: mpd.service: Consumed 2min 9.333s CPU time. May 25 21:42:25 volumio volumio[1204]: info: MRS: STOPPING volumioStreaming May 25 21:42:25 volumio sudo[18317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 25 21:42:25 volumio sudo[18317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:25 volumio sudo[18319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream402.ts /tmp/hls/stream403.ts /tmp/hls/stream404.ts /tmp/hls/stream405.ts May 25 21:42:25 volumio sudo[18319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:25 volumio sudo[18319]: pam_unix(sudo:session): session closed for user root May 25 21:42:25 volumio volumio[1204]: info: MRS: Removed streaming files May 25 21:42:25 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... May 25 21:42:25 volumio systemd[1]: volumioStreaming.service: Killing process 17515 (ffmpeg) with signal SIGKILL. May 25 21:42:25 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. May 25 21:42:25 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. May 25 21:42:26 volumio systemd[1]: volumioStreaming.service: Consumed 1min 28.680s CPU time. May 25 21:42:26 volumio sudo[18317]: pam_unix(sudo:session): session closed for user root May 25 21:42:26 volumio volumio[1204]: info: MRS: volumioStreaming STOPPED May 25 21:42:41 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput May 25 21:42:41 volumio volumio[1204]: info: MRS: Starting sonos device: [object Object] May 25 21:42:41 volumio volumio[1204]: info: Enabled audio output: uuid:RINCON_48A6B84726E701400 May 25 21:42:41 volumio volumio[1204]: info: MRS: Setting this device as Streaming Server May 25 21:42:41 volumio volumio[1204]: info: May 25 21:42:41 volumio volumio[1204]: [1779763361458] ---------------------------- MRS: Setting Streaming Server May 25 21:42:41 volumio volumio[1204]: info: MRS: enable multiroom server output May 25 21:42:41 volumio volumio[1204]: info: MRS: Set multiroom target PCM to volumioMultiRoom May 25 21:42:41 volumio volumio[1204]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom May 25 21:42:41 volumio volumio[1204]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 25 21:42:41 volumio volumio[1204]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback May 25 21:42:41 volumio volumio[1204]: info: MRS: STARTING volumioStreaming May 25 21:42:41 volumio sudo[18344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 25 21:42:41 volumio sudo[18344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:41 volumio sudo[18344]: pam_unix(sudo:session): session closed for user root May 25 21:42:41 volumio sudo[18346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 25 21:42:41 volumio sudo[18346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:41 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. May 25 21:42:41 volumio sudo[18346]: pam_unix(sudo:session): session closed for user root May 25 21:42:41 volumio volumio[1204]: info: MRS: volumioStreaming STARTED May 25 21:42:41 volumio sudo[18350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 25 21:42:41 volumio sudo[18350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:41 volumio sudo[18350]: pam_unix(sudo:session): session closed for user root May 25 21:42:45 volumio volumio[1204]: info: MRS: Starting sonos device: uuid:RINCON_48A6B84726E701400 May 25 21:42:45 volumio volumio[1204]: info: Refreshing Cached IP Addresses May 25 21:42:45 volumio sudo[18368]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 21:42:45 volumio sudo[18368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:45 volumio sudo[18368]: pam_unix(sudo:session): session closed for user root May 25 21:42:45 volumio sudo[18370]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 21:42:45 volumio sudo[18370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:45 volumio sudo[18370]: pam_unix(sudo:session): session closed for user root May 25 21:42:45 volumio volumio[1204]: error: MRS: Error starting sonos device: uuid:RINCON_48A6B84726E701400:Error: upnp: statusCode 500 & upnpErrorCode s:ClientUPnPError800 May 25 21:42:48 volumio volumio[1204]: info: CorePlayQueue::getTrack 2 May 25 21:42:48 volumio volumio[1204]: info: CorePlayQueue::getTrack 3 May 25 21:42:48 volumio volumio[1204]: info: Prefetching next song May 25 21:42:48 volumio volumio[1204]: info: [1779763368342] ControllerQobuz::prefetch May 25 21:42:48 volumio volumio[1204]: info: getStreamUrl took 419 milliseconds May 25 21:42:48 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1238070&eid=182514682&fmt=27&profile=raw&app_id=539451548&cid=3412292&etsp=1779766968&hmac=u07yMCdCQHyzqmD98fCd9H2KHJ0" May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::getTrack 3 May 25 21:42:52 volumio volumio[1204]: info: Preload queue cleared May 25 21:42:52 volumio volumio[1204]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::ClearQueue May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::stop May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::stPlaybackTimer May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::updateTrackBlock May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::getTrackBlock May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::pushState May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::getTrack 3 May 25 21:42:52 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:42:52 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState May 25 21:42:52 volumio volumio[1204]: info: MRS: Pushing multiroomSync output update for this device May 25 21:42:52 volumio volumio[1204]: info: MRS: Pushing multiroomSync output May 25 21:42:52 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::getTrack 3 May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::serviceStop May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::getTrack 3 May 25 21:42:52 volumio volumio[1204]: info: CoreCommandRouter::serviceStop May 25 21:42:52 volumio volumio[1204]: info: [1779763372876] ControllerQobuz::stop May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 21:42:52 volumio volumio[1204]: info: ControllerMpd::stop May 25 21:42:52 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::clearPlayQueue May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::saveQueue May 25 21:42:52 volumio volumio[1204]: info: CoreCommandRouter::volumioPushQueue May 25 21:42:52 volumio volumio[1204]: info: CoreStateMachine::addQueueItems May 25 21:42:52 volumio volumio[1204]: info: CorePlayQueue::addQueueItems May 25 21:42:52 volumio volumio[1204]: info: Preload queue cleared May 25 21:42:52 volumio volumio[1204]: info: Adding Item to queue: qobuz://album/cq9okzc9nwkab May 25 21:42:52 volumio volumio[1204]: info: Exploding uri qobuz://album/cq9okzc9nwkab in service qobuz May 25 21:42:52 volumio volumio[1204]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 21:42:52 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:52.881-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%b5,192.168.1.2:62663 @ 0x3078420" state=STATUS_STOPPED positionMs=0 volume=100 May 25 21:42:52 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:52.881-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:62663 @ 0x2c8d2c0" state=STATUS_STOPPED positionMs=0 volume=100 May 25 21:42:52 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:52.882-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%b5,192.168.1.2:62663 @ 0x3078420" id=qobuz://song/182514682 title="Midnite Cruiser" May 25 21:42:52 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:52.882-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:62663 @ 0x2c8d2c0" id=qobuz://song/182514682 title="Midnite Cruiser" May 25 21:42:53 volumio volumio[1204]: info: explodeUri took 170 milliseconds May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::volumioPushQueue May 25 21:42:53 volumio volumio[1204]: info: CorePlayQueue::saveQueue May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::updateTrackBlock May 25 21:42:53 volumio volumio[1204]: info: CorePlayQueue::getTrackBlock May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::play index 0 May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::stop May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::play index undefined May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:42:53 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::startPlaybackTimer May 25 21:42:53 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 May 25 21:42:53 volumio volumio[1204]: info: [1779763373058] ControllerQobuz::clearAddPlayTrack May 25 21:42:53 volumio volumio[1204]: info: getStreamUrl took 195 milliseconds May 25 21:42:53 volumio volumio[1204]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:42:53 volumio volumio[1204]: info: CoreStateMachine::pushState May 25 21:42:53 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState May 25 21:42:53 volumio volumio[1204]: info: MRS: Pushing multiroomSync output update for this device May 25 21:42:53 volumio volumio[1204]: info: MRS: Pushing multiroomSync output May 25 21:42:53 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState May 25 21:42:53 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 May 25 21:42:53 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:53.340-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%b5,192.168.1.2:62663 @ 0x3078420" state=STATUS_STOPPED positionMs=250 volume=100 May 25 21:42:53 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:53.341-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:62663 @ 0x2c8d2c0" state=STATUS_STOPPED positionMs=250 volume=100 May 25 21:42:53 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:53.341-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%b5,192.168.1.2:62663 @ 0x3078420" id=qobuz://song/182514679 title="Do It Again" May 25 21:42:53 volumio volumio5-onboarding[1396]: time=2026-05-25T21:42:53.342-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:62663 @ 0x2c8d2c0" id=qobuz://song/182514679 title="Do It Again" May 25 21:42:55 volumio volumio[1204]: info: MRS: STARTING volumioStreaming May 25 21:42:55 volumio sudo[18393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 25 21:42:55 volumio sudo[18393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:55 volumio sudo[18393]: pam_unix(sudo:session): session closed for user root May 25 21:42:55 volumio sudo[18395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 25 21:42:55 volumio volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:42:55 volumio sudo[18395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:55 volumio volumio[1204]: Error: upnp: statusCode 500 & upnpErrorCode s:ClientUPnPError701 May 25 21:42:55 volumio volumio[1204]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/sonos/lib/services/Service.js:87:25 May 25 21:42:55 volumio volumio[1204]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 21:42:55 volumio volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:42:55 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... May 25 21:42:55 volumio systemd[1]: volumioStreaming.service: Killing process 18352 (ffmpeg) with signal SIGKILL. May 25 21:42:55 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. May 25 21:42:55 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. May 25 21:42:56 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. May 25 21:42:56 volumio sudo[18395]: pam_unix(sudo:session): session closed for user root May 25 21:42:56 volumio sudo[18411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 25 21:42:56 volumio sudo[18411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:42:56 volumio sudo[18411]: pam_unix(sudo:session): session closed for user root May 25 21:42:56 volumio sudo[18416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 21:41' May 25 21:42:56 volumio sudo[18416]: 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"