Feb 12 22:05:04 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:04 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:09 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 22:05:09 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 22:05:15 mac volumio[1231]: info: Stopping peppymeterbasic service Feb 12 22:05:15 mac volumio[1231]: info: CoreStateMachine::stop Feb 12 22:05:15 mac volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:05:15 mac volumio[1231]: info: CoreStateMachine::updateTrackBlock Feb 12 22:05:15 mac volumio[1231]: info: CorePlayQueue::getTrackBlock Feb 12 22:05:15 mac volumio[1231]: info: CoreStateMachine::stPlaybackTimer Feb 12 22:05:15 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:15 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:15 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 22:05:15 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:15 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:15 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:15 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:15 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:15 mac volumio[1231]: info: CoreStateMachine::serviceStop Feb 12 22:05:15 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:15 mac volumio[1231]: info: CoreCommandRouter::serviceStop Feb 12 22:05:15 mac volumio[1231]: info: ControllerMpd::stop Feb 12 22:05:15 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 22:05:15 mac volumio[1231]: info: Disabling plugin peppymeterbasic Feb 12 22:05:15 mac volumio[1231]: info: Preparing to generate the ALSA configuration file Feb 12 22:05:16 mac volumio[1231]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:05:16 mac sudo[3025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:05:16 mac sudo[3025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:16 mac volumio[1231]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 12 22:05:16 mac volumio[1231]: info: Reading ALSA contributions from plugins. Feb 12 22:05:16 mac volumio[1231]: info: Feb 12 22:05:16 mac volumio[1231]: ---------------------------- MPD announces state update: player Feb 12 22:05:16 mac volumio[1231]: info: sendMpdCommand stop took 999 milliseconds Feb 12 22:05:16 mac volumio[1231]: info: ControllerMpd::getState Feb 12 22:05:16 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:05:16 mac volumio[1231]: info: sendMpdCommand status took 27 milliseconds Feb 12 22:05:16 mac volumio[1231]: verbose: ControllerMpd::parseState Feb 12 22:05:16 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:05:16 mac volumio[1231]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 22:05:16 mac volumio[1231]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:05:16 mac volumio[1231]: info: ControllerMpd::pushState Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::servicePushState Feb 12 22:05:16 mac sudo[3028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:05:16 mac sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:16 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:16 mac sudo[3025]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:16 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:16 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A New Flame","artist":null,"album":null,"uri":"USB/DEMAG/Song book simply red/A New Flame.mp3","trackType":"mp3"} Feb 12 22:05:16 mac volumio[1231]: verbose: CURRENT POSITION 0 Feb 12 22:05:16 mac volumio[1231]: info: CoreStateMachine::syncState stateService stop Feb 12 22:05:16 mac volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:05:16 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:16 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:16 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: info: No code Feb 12 22:05:16 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:16 mac sudo[3028]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:16 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:16 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:16 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:16 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:16 mac volumio[1231]: info: ------------------------------ 290ms Feb 12 22:05:16 mac volumio[1231]: info: peppymeterbasic Daemon Stop Feb 12 22:05:17 mac volumio[1231]: info: Asound.conf file written Feb 12 22:05:17 mac sudo[3038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 12 22:05:17 mac sudo[3038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:17 mac sudo[3038]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:17 mac volumio[1231]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 12 22:05:17 mac volumio[1231]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 12 22:05:17 mac volumio[1231]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 12 22:05:17 mac volumio[1231]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 12 22:05:17 mac volumio[1231]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 12 22:05:17 mac volumio[1231]: info: Output device has changed, restarting MPD Feb 12 22:05:17 mac volumio[1231]: info: Output device has changed, restarting Shairport Sync Feb 12 22:05:17 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:17 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 22:05:17 mac sudo[3044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 22:05:17 mac sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:17 mac sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 22:05:17 mac sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:17 mac sudo[3044]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:17 mac systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 22:05:17 mac volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 22:05:17 mac volumio[1231]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 12 22:05:17 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:17 mac volumio[1231]: info: QobuzConnect: setDeactiveState invoked Feb 12 22:05:17 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:17 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:17 mac volumio[1231]: info: Done. Feb 12 22:05:17 mac sudo[3065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 12 22:05:17 mac sudo[3063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 12 22:05:17 mac sudo[3063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:17 mac sudo[3065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:17 mac systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 12 22:05:17 mac systemd[1]: vtcs.service: Deactivated successfully. Feb 12 22:05:17 mac systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 12 22:05:17 mac sudo[3074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 12 22:05:17 mac sudo[3074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:17 mac sudo[3063]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:17 mac sudo[3065]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:18 mac sudo[3074]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:18 mac volumio[1231]: info: MPD Permissions set Feb 12 22:05:18 mac volumio[1231]: info: Starting Shairport Sync Feb 12 22:05:18 mac sudo[3076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 12 22:05:18 mac sudo[3076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac sudo[3082]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 22:05:18 mac sudo[3082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:18 mac systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 12 22:05:18 mac systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 22:05:18 mac systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 22:05:18 mac systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 22:05:18 mac systemd[1]: shairport-sync.service: Consumed 2.436s CPU time. Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:05:18 mac systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 22:05:18 mac sudo[3082]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:18 mac volumio[1231]: info: Shairport-Sync Started Feb 12 22:05:18 mac qobuz-connect[2336]: 20260212 22:05:18.337 [2336.2336] INFO SampleApp: Stopping Local configuration server Feb 12 22:05:18 mac qobuz-connect[2336]: 20260212 22:05:18.358 [2336.2336] INFO SampleApp: shat down connection on UNIX socket Feb 12 22:05:18 mac volumio[1231]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 12 22:05:18 mac volumio[1231]: info: QobuzConnect: setDeactiveState invoked Feb 12 22:05:18 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:18 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:19 mac systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 12 22:05:19 mac systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 12 22:05:19 mac systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 12 22:05:19 mac sudo[3076]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:20 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:20 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:20 mac volumio[1231]: info: Executing endpoint qc_getconfig Feb 12 22:05:20 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 12 22:05:20 mac volumio[1231]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 12 22:05:20 mac volumio[1231]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.878 [3090.3090] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.931 [3090.3090] INFO VolumeManager: [0x34a5c8]: Setting new playback volume: 75 Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.933 [3090.3090] INFO VolumeManager: [0x34a5c8]: Setting new mute state: 0 Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.933 [3090.3090] INFO QobuzConnect: [0x34af98]: Client initialized! Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.933 [3090.3090] INFO SampleApp: Starting Avahi advertising, name: Mac, service name: _qobuz-connect._tcp Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.959 [3090.3090] INFO LocalConfigManager: [0x349ea8]: Starting Local Configuration server Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.959 [3090.3090] INFO SampleApp: Starting Local configuration server Feb 12 22:05:20 mac qobuz-connect[3090]: 20260212 22:05:20.960 [3090.3090] INFO SampleApp: Connected to UNIX socket client 0x334818 Feb 12 22:05:21 mac qobuz-connect[3090]: 20260212 22:05:21.027 [3090.3090] INFO SampleApp: Playback volume changed: 75 Feb 12 22:05:21 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:21 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:21 mac volumio[1231]: info: TidalConnect service stoped! Feb 12 22:05:21 mac volumio[1231]: info: TidalConnect service stoped! Feb 12 22:05:21 mac volumio[1231]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 12 22:05:21 mac volumio[1231]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 12 22:05:21 mac sudo[3120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 12 22:05:21 mac sudo[3120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:21 mac systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 12 22:05:21 mac sudo[3120]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:23 mac volumio[1231]: info: Executing endpoint tc_getconfig Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 12 22:05:23 mac vtcs[3123]: STARTING TidalConnect services, version: 1.6.1 Feb 12 22:05:23 mac vtcs[3123]: STARTED TidalConnect services. Feb 12 22:05:23 mac volumio[1231]: info: Executing endpoint tc_connect Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 12 22:05:23 mac volumio[1231]: info: Connecting to TidalConnect Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::servicePushState Feb 12 22:05:23 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:23 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:23 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:23 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:23 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:23 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:23 mac volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::servicePushState Feb 12 22:05:23 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:23 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:23 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:23 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:23 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:23 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:23 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:23 mac volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 12 22:05:24 mac systemd[1]: mpd.service: Deactivated successfully. Feb 12 22:05:24 mac systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 22:05:24 mac systemd[1]: mpd.service: Consumed 12.668s CPU time. Feb 12 22:05:24 mac systemd[1]: mpd.socket: Deactivated successfully. Feb 12 22:05:24 mac systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 22:05:24 mac systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 22:05:24 mac volumio[1231]: error: Upnp client error: Error: read ECONNRESET Feb 12 22:05:24 mac systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 22:05:24 mac systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 22:05:24 mac volumio[1231]: info: TidalConnect service started! Feb 12 22:05:24 mac sudo[3154]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 22:05:24 mac sudo[3154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:05:24 mac sudo[3154]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:30 mac mpd[3156]: 2026-02-12T22:05:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 22:05:30 mac systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 22:05:30 mac sudo[3046]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:30 mac volumio[1231]: error: updateQueue error: null Feb 12 22:05:34 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Feb 12 22:05:34 mac volumio[1231]: info: MRS: Starting sonos device: [object Object] Feb 12 22:05:34 mac volumio[1231]: info: Enabled audio output: uuid:RINCON_B8E9372FC2B801400 Feb 12 22:05:34 mac volumio[1231]: info: MRS: Setting this device as Streaming Server Feb 12 22:05:34 mac volumio[1231]: info: Feb 12 22:05:34 mac volumio[1231]: [1770933934885] ---------------------------- MRS: Setting Streaming Server Feb 12 22:05:34 mac volumio[1231]: info: MRS: enable multiroom server output Feb 12 22:05:34 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioMultiRoom Feb 12 22:05:34 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Feb 12 22:05:34 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 12 22:05:34 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Feb 12 22:05:34 mac volumio[1231]: info: MRS: STARTING volumioStreaming Feb 12 22:05:35 mac sudo[3182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 12 22:05:35 mac sudo[3180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 12 22:05:35 mac sudo[3182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:35 mac sudo[3180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:35 mac sudo[3180]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:35 mac systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Feb 12 22:05:35 mac sudo[3182]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:35 mac volumio[1231]: info: MRS: volumioStreaming STARTED Feb 12 22:05:35 mac sudo[3186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 12 22:05:35 mac sudo[3186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:05:35 mac sudo[3186]: pam_unix(sudo:session): session closed for user root Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::volumioPlay Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::play index undefined Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::startPlaybackTimer Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::clearAddPlayTracks USB/DEMAG/Song book simply red/A New Flame.mp3 Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand stop took 1 milliseconds Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 22:05:43 mac volumio[1231]: info: Feb 12 22:05:43 mac volumio[1231]: ---------------------------- MPD announces system playlist update Feb 12 22:05:43 mac volumio[1231]: info: Ignoring MPD Status Update Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand clear took 1 milliseconds Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "USB/DEMAG/Song book simply red/A New Flame.mp3" Feb 12 22:05:43 mac volumio[1231]: error: updateQueue error: null Feb 12 22:05:43 mac volumio[1231]: info: ------------------------------ 3ms Feb 12 22:05:43 mac volumio[1231]: info: Feb 12 22:05:43 mac volumio[1231]: ---------------------------- MPD announces system playlist update Feb 12 22:05:43 mac volumio[1231]: info: Ignoring MPD Status Update Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand add "USB/DEMAG/Song book simply red/A New Flame.mp3" took 5 milliseconds Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Feb 12 22:05:43 mac volumio[1231]: info: ------------------------------ 3ms Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand play took 2 milliseconds Feb 12 22:05:43 mac volumio[1231]: info: Feb 12 22:05:43 mac volumio[1231]: ---------------------------- MPD announces state update: player Feb 12 22:05:43 mac volumio[1231]: info: ControllerMpd::getState Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:05:43 mac volumio[1231]: info: Feb 12 22:05:43 mac volumio[1231]: ---------------------------- MPD announces state update: player Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand status took 208 milliseconds Feb 12 22:05:43 mac volumio[1231]: info: ControllerMpd::getState Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::parseState Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand status took 3 milliseconds Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::parseState Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:05:43 mac volumio[1231]: info: ControllerMpd::pushState Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::servicePushState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A New Flame","artist":null,"album":null,"uri":"USB/DEMAG/Song book simply red/A New Flame.mp3","trackType":"mp3"} Feb 12 22:05:43 mac volumio[1231]: verbose: CURRENT POSITION 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::syncState stateService play Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:05:43 mac volumio[1231]: info: ------------------------------ 219ms Feb 12 22:05:43 mac volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 22:05:43 mac volumio[1231]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:05:43 mac volumio[1231]: info: ControllerMpd::pushState Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::servicePushState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A New Flame","artist":null,"album":null,"uri":"USB/DEMAG/Song book simply red/A New Flame.mp3","trackType":"mp3"} Feb 12 22:05:43 mac volumio[1231]: verbose: CURRENT POSITION 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::syncState stateService play Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::syncState currentStatus play Feb 12 22:05:43 mac volumio[1231]: info: Received an update from plugin. extracting info from payload Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:43 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:43 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreStateMachine::pushState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::volumioPushState Feb 12 22:05:43 mac volumio[1231]: info: MRS: Pushing multiroomSync output update for this device Feb 12 22:05:43 mac volumio[1231]: info: MRS: Pushing multiroomSync output Feb 12 22:05:43 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:05:43 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:05:43 mac volumio[1231]: info: ------------------------------ 79ms Feb 12 22:05:43 mac volumio[1231]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 12 22:06:10 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 22:06:10 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 22:06:16 mac volumio[1231]: warn: [now-playing] MyBackgroundMonitor stopped Feb 12 22:06:16 mac volumio[1231]: info: Disabling plugin now_playing Feb 12 22:06:16 mac volumio[1231]: info: Done. Feb 12 22:06:21 mac volumio[1231]: info: CoreCommandRouter::volumioGetState Feb 12 22:06:21 mac volumio[1231]: info: CorePlayQueue::getTrack 0 Feb 12 22:06:31 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Feb 12 22:06:31 mac volumio[1231]: info: MRS: Setting this device as single Feb 12 22:06:31 mac volumio[1231]: info: Feb 12 22:06:31 mac volumio[1231]: [1770933991665] ---------------------------- Setting Multiroom Single Feb 12 22:06:31 mac volumio[1231]: info: MRS: disable multiroom output Feb 12 22:06:31 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioDiscard Feb 12 22:06:31 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Feb 12 22:06:31 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Feb 12 22:06:31 mac volumio[1231]: info: MRS: Starting sonos device: [object Object] Feb 12 22:06:31 mac volumio[1231]: info: Enabled audio output: uuid:RINCON_7828CA54C55D01400 Feb 12 22:06:31 mac volumio[1231]: info: MRS: Setting this device as Streaming Server Feb 12 22:06:31 mac volumio[1231]: info: Feb 12 22:06:31 mac volumio[1231]: [1770933991940] ---------------------------- MRS: Setting Streaming Server Feb 12 22:06:31 mac volumio[1231]: info: MRS: enable multiroom server output Feb 12 22:06:31 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioMultiRoom Feb 12 22:06:31 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Feb 12 22:06:31 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 12 22:06:31 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Feb 12 22:06:31 mac volumio[1231]: info: MRS: STARTING volumioStreaming Feb 12 22:06:32 mac sudo[3291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 12 22:06:32 mac sudo[3289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts /tmp/hls/stream46.ts Feb 12 22:06:32 mac sudo[3289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:32 mac sudo[3291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:32 mac sudo[3289]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:32 mac systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Feb 12 22:06:32 mac systemd[1]: volumioStreaming.service: Killing process 3188 (ffmpeg) with signal SIGKILL. Feb 12 22:06:32 mac systemd[1]: volumioStreaming.service: Killing process 3213 (ffmpeg) with signal SIGKILL. Feb 12 22:06:32 mac systemd[1]: volumioStreaming.service: Killing process 3214 (ffmpeg) with signal SIGKILL. Feb 12 22:06:32 mac systemd[1]: volumioStreaming.service: Deactivated successfully. Feb 12 22:06:32 mac systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Feb 12 22:06:32 mac systemd[1]: volumioStreaming.service: Consumed 13.905s CPU time. Feb 12 22:06:32 mac systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Feb 12 22:06:32 mac sudo[3291]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:32 mac volumio[1231]: info: MRS: volumioStreaming STARTED Feb 12 22:06:32 mac sudo[3295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 12 22:06:32 mac sudo[3295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:32 mac sudo[3295]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:32 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 12 22:06:32 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Feb 12 22:06:33 mac volumio[1231]: info: MRS: STOPPING volumioStreaming Feb 12 22:06:33 mac sudo[3300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 12 22:06:33 mac sudo[3300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:33 mac sudo[3302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 12 22:06:33 mac sudo[3302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:33 mac sudo[3302]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:33 mac volumio[1231]: info: MRS: Removed streaming files Feb 12 22:06:33 mac systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Feb 12 22:06:33 mac systemd[1]: volumioStreaming.service: Killing process 3297 (ffmpeg) with signal SIGKILL. Feb 12 22:06:33 mac systemd[1]: volumioStreaming.service: Deactivated successfully. Feb 12 22:06:33 mac systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Feb 12 22:06:33 mac sudo[3300]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:33 mac volumio[1231]: info: MRS: volumioStreaming STOPPED Feb 12 22:06:36 mac volumio[1231]: info: MRS: Starting sonos device: uuid:RINCON_7828CA54C55D01400 Feb 12 22:06:36 mac volumio[1231]: info: Refreshing Cached IP Addresses Feb 12 22:06:36 mac sudo[3320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 22:06:36 mac sudo[3320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:36 mac sudo[3322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 22:06:36 mac sudo[3320]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:36 mac sudo[3322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:06:36 mac sudo[3322]: pam_unix(sudo:session): session closed for user root Feb 12 22:06:36 mac volumio[1231]: error: MRS: Error starting sonos device: uuid:RINCON_7828CA54C55D01400:Error: upnp: statusCode 500 & upnpErrorCode s:ClientUPnPError800 Feb 12 22:06:40 mac volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Feb 12 22:06:40 mac volumio[1231]: info: MRS: Setting this device as single Feb 12 22:06:40 mac volumio[1231]: info: Feb 12 22:06:40 mac volumio[1231]: [1770934000197] ---------------------------- Setting Multiroom Single Feb 12 22:06:40 mac volumio[1231]: info: MRS: disable multiroom output Feb 12 22:06:40 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioDiscard Feb 12 22:06:40 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Feb 12 22:06:40 mac volumio[1231]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 12 22:06:40 mac volumio[1231]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Feb 12 22:06:41 mac volumio[1231]: info: MRS: Stopping sonos device: uuid:RINCON_7828CA54C55D01400 Feb 12 22:06:41 mac volumio[1231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 22:06:41 mac volumio[1231]: Error: upnp: statusCode 500 & upnpErrorCode s:ClientUPnPError701 Feb 12 22:06:41 mac volumio[1231]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/sonos/lib/services/Service.js:87:25 Feb 12 22:06:41 mac volumio[1231]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 12 22:06:41 mac volumio[1231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 22:06:44 mac sudo[3358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 22:05' Feb 12 22:06:44 mac sudo[3358]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"