Mar 25 12:11:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:04 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:44 volumio-5-living-room kernel: usb 1-1: USB disconnect, device number 2 Mar 25 12:11:44 volumio-5-living-room thd[1030]: Error reading device '/dev/input/event5' Mar 25 12:11:44 volumio-5-living-room thd[1030]: Error reading device '/dev/input/event5' Mar 25 12:11:44 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 3 using xhci-hcd Mar 25 12:11:45 volumio-5-living-room kernel: usb 1-1: New USB device found, idVendor=17ae, idProduct=0010, bcdDevice= 1.00 Mar 25 12:11:45 volumio-5-living-room kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 12:11:45 volumio-5-living-room kernel: usb 1-1: Product: NODE ICON Mar 25 12:11:45 volumio-5-living-room kernel: usb 1-1: Manufacturer: Bluesound Mar 25 12:11:45 volumio-5-living-room kernel: usb 1-1: SerialNumber: 9056821c196e Mar 25 12:11:45 volumio-5-living-room kernel: input: Bluesound NODE ICON as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:17AE:0010.0002/input/input6 Mar 25 12:11:45 volumio-5-living-room kernel: hid-generic 0003:17AE:0010.0002: input,hidraw0: USB HID v1.01 Device [Bluesound NODE ICON] on usb-xhci-hcd.0-1/input2 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:45 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Detached Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 25 12:11:45 volumio-5-living-room (udev-worker)[8295]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:45 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Attached Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Preparing to save Alsa Options, stopping services first Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPause Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pause Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NODE ICON","alsacard":"ICON"},"i2s":false} Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Setting mixer Playback Volume for card NODE ICON Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:45 volumio-5-living-room kernel: usb 1-1: USB disconnect, device number 3 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:45 volumio-5-living-room vtcs[6552]: [2026-03-25 12:11:45.773] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 25 12:11:45 volumio-5-living-room vtcs[6552]: [2026-03-25 12:11:45.776] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 25 12:11:45 volumio-5-living-room sudo[8320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:45 volumio-5-living-room sudo[8320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Updating Volume Controller Parameters: Device: 5 Name: NODE ICON Mixer: Playback Volume Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Disabling external Volume Control Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:11:45 volumio-5-living-room systemd[1]: vtcs.service: Deactivated successfully. Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 25 12:11:45 volumio-5-living-room sudo[8320]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:45 volumio-5-living-room sudo[8327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:45 volumio-5-living-room sudo[8327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room sudo[8335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:45 volumio-5-living-room sudo[8335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:11:45 volumio-5-living-room volumio[1420]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=undefined Mute =false Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:45 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:45.866Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:45 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:45.867Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:45 volumio-5-living-room sudo[8335]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:45 volumio-5-living-room sudo[8327]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:45 volumio-5-living-room sudo[8340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:45 volumio-5-living-room sudo[8340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 25 12:11:45 volumio-5-living-room qobuz-connect[6532]: 20260325 12:11:45.891 [6532.6532] INFO SampleApp: Stopping Local configuration server Mar 25 12:11:45 volumio-5-living-room volumio[1420]: Invalid card number '5'. Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 25 12:11:45 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Asound.conf file unchanged, so no further update is needed Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:45 volumio-5-living-room sudo[8350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:11:45 volumio-5-living-room sudo[8350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room sudo[8350]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:45 volumio-5-living-room sudo[8352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:11:45 volumio-5-living-room sudo[8352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:45 volumio-5-living-room systemd[1]: musicservicesshield.service: Deactivated successfully. Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 25 12:11:45 volumio-5-living-room sudo[8362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:45 volumio-5-living-room sudo[8362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room sudo[8364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:45 volumio-5-living-room sudo[8364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:11:45 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room sudo[8372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:45 volumio-5-living-room sudo[8372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:45 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:45 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:11:45 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:11:45 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:11:46 volumio-5-living-room sudo[8381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:11:46 volumio-5-living-room sudo[8381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Asound.conf file written Mar 25 12:11:46 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:11:46 volumio-5-living-room sudo[8386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 25 12:11:46 volumio-5-living-room sudo[8386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8386]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:46 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:46 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:11:46 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:46 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:11:46 volumio-5-living-room sudo[8372]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:46 volumio-5-living-room sudo[8393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:11:46 volumio-5-living-room sudo[8393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8393]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room sudo[8362]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room sudo[8364]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:11:46 volumio-5-living-room sudo[8395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room sudo[8395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:46 volumio-5-living-room sudo[8398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:46 volumio-5-living-room sudo[8387]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:11:46 volumio-5-living-room sudo[8387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:11:46 volumio-5-living-room sudo[8387]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room sudo[8410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:46 volumio-5-living-room sudo[8410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:46 volumio-5-living-room sudo[8413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room sudo[8421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:46 volumio-5-living-room sudo[8421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:11:46 volumio-5-living-room sudo[8429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:11:46 volumio-5-living-room sudo[8429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8421]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:11:46 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:46 volumio-5-living-room systemd[1]: shairport-sync.service: Consumed 2.044s CPU time. Mar 25 12:11:46 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:11:46 volumio-5-living-room sudo[8431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:46 volumio-5-living-room sudo[8431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8410]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room sudo[8413]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:11:46 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:11:46 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:46 volumio-5-living-room sudo[8381]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room sudo[8429]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 4 using xhci-hcd Mar 25 12:11:46 volumio-5-living-room sudo[8437]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:11:46 volumio-5-living-room sudo[8437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:11:46 volumio-5-living-room sudo[8437]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: New USB device found, idVendor=17ae, idProduct=0010, bcdDevice= 1.00 Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: Product: NODE ICON Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: Manufacturer: Bluesound Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: SerialNumber: 9056821c196e Mar 25 12:11:46 volumio-5-living-room kernel: input: Bluesound NODE ICON as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:17AE:0010.0003/input/input7 Mar 25 12:11:46 volumio-5-living-room kernel: hid-generic 0003:17AE:0010.0003: input,hidraw0: USB HID v1.01 Device [Bluesound NODE ICON] on usb-xhci-hcd.0-1/input2 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Detached Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 25 12:11:46 volumio-5-living-room (udev-worker)[8296]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Attached Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Preparing to save Alsa Options, stopping services first Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPause Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pause Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NODE ICON","alsacard":"ICON"},"i2s":false} Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Setting mixer Playback Volume for card NODE ICON Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Updating Volume Controller Parameters: Device: 5 Name: NODE ICON Mixer: Playback Volume Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Disabling external Volume Control Mar 25 12:11:46 volumio-5-living-room sudo[8479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:46 volumio-5-living-room sudo[8479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 12:11:46 volumio-5-living-room kernel: usb 1-1: USB disconnect, device number 4 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:11:46 volumio-5-living-room sudo[8479]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room sudo[8485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:46 volumio-5-living-room sudo[8485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:11:46 volumio-5-living-room volumio[1420]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib confmisc.c:855:(parse_card) cannot find card '5' Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib conf.c:5180:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib conf.c:5180:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib confmisc.c:1334:(snd_func_refer) error evaluating name Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib conf.c:5180:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib conf.c:5703:(snd_config_expand) Evaluate error: No such file or directory Mar 25 12:11:46 volumio-5-living-room volumio[1420]: ALSA lib control.c:1528:(snd_ctl_open_noupdate) Invalid CTL sysdefault:5 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: amixer: Mixer attach sysdefault:5 error: No such file or directory Mar 25 12:11:46 volumio-5-living-room sudo[8493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:46 volumio-5-living-room sudo[8493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8485]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=undefined Mute =false Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:46 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:46.854Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:46 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:46.854Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:46 volumio-5-living-room sudo[8493]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:46 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:46 volumio-5-living-room sudo[8497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:46 volumio-5-living-room sudo[8497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:46 volumio-5-living-room volumio[1420]: Invalid card number '5'. Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Asound.conf file unchanged, so no further update is needed Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:46 volumio-5-living-room sudo[8506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:11:46 volumio-5-living-room sudo[8506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:11:46 volumio-5-living-room sudo[8506]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room sudo[8508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:11:46 volumio-5-living-room sudo[8508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:46 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:11:46 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:11:46 volumio-5-living-room sudo[8518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:46 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:11:46 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:11:46 volumio-5-living-room sudo[8518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room sudo[8520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:46 volumio-5-living-room sudo[8520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:46 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:46 volumio-5-living-room sudo[8529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:46 volumio-5-living-room sudo[8529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:47 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:11:47 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:11:47 volumio-5-living-room sudo[8538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:11:47 volumio-5-living-room sudo[8538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:47 volumio-5-living-room sudo[8529]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:11:47 volumio-5-living-room sudo[8540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:47 volumio-5-living-room sudo[8540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:47 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:11:47 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:47 volumio-5-living-room sudo[8536]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:11:47 volumio-5-living-room sudo[8536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:11:47 volumio-5-living-room sudo[8536]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8520]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8518]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:47 volumio-5-living-room sudo[8538]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 25 12:11:47 volumio-5-living-room volumio[1420]: verbose: New Socket.io Connection to 192.168.50.76 from 192.168.50.22 UA: Mozilla/5.0 (Linux; Android 16; 24129PN74G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 12:11:47 volumio-5-living-room qobuz-connect[6532]: 20260325 12:11:47.595 [6532.6532] INFO SampleApp: shat down connection on UNIX socket Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:47 volumio-5-living-room systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 25 12:11:47 volumio-5-living-room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:11:47 volumio-5-living-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:11:47 volumio-5-living-room sudo[8398]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8340]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8540]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8497]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8431]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Executing endpoint qc_getconfig Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.665 [8561.8561] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.668 [8561.8561] INFO VolumeManager: [0x1a27e68]: Setting new playback volume: 75 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.669 [8561.8561] INFO VolumeManager: [0x1a27e68]: Setting new mute state: 0 Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.669 [8561.8561] INFO AudioStreamManager: [0x1a27bc0]: Setting new audio download buffer size: 1048576 Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.669 [8561.8561] INFO QobuzConnect: [0x1a28730]: Client initialized! Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.669 [8561.8561] INFO SampleApp: Starting Avahi advertising, name: Volumio 5 Living Room, service name: _qobuz-connect._tcp Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.677 [8561.8561] INFO LocalConfigManager: [0x1a278e8]: Starting Local Configuration server Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.677 [8561.8561] INFO SampleApp: Starting Local configuration server Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.677 [8561.8561] INFO SampleApp: Connected to UNIX socket client 0x1a12818 Mar 25 12:11:47 volumio-5-living-room mpd[8543]: 2026-03-25T12:11:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 25 12:11:47 volumio-5-living-room systemd[1]: Started mpd.service - Music Player Daemon. Mar 25 12:11:47 volumio-5-living-room sudo[8508]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8395]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room sudo[8352]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:47 volumio-5-living-room volumio[1420]: error: updateQueue error: null Mar 25 12:11:47 volumio-5-living-room volumio[1420]: error: MPD error: The expression evaluated to a falsy value: Mar 25 12:11:47 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:11:47 volumio-5-living-room volumio[1420]: error: The expression evaluated to a falsy value: Mar 25 12:11:47 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:11:47 volumio-5-living-room kernel: usb 4-1: USB disconnect, device number 2 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: error: updateQueue error: null Mar 25 12:11:47 volumio-5-living-room systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:11:47 volumio-5-living-room bash[8577]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 36: cset: command not found Mar 25 12:11:47 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:47.820 [8561.8561] INFO SampleApp: Playback volume changed: 75 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:47 volumio-5-living-room bash[8578]: /data/plugins/system_hardware/music_services_shield/moveprocess.sh: 5: cset: not found Mar 25 12:11:47 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 5 using xhci-hcd Mar 25 12:11:47 volumio-5-living-room bash[8582]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 49: cset: command not found Mar 25 12:11:47 volumio-5-living-room systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Received Get System Info Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Discovery: Getting this device information Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:47 volumio-5-living-room volumio[1420]: info: Listing playlists Mar 25 12:11:48 volumio-5-living-room kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Mar 25 12:11:48 volumio-5-living-room kernel: usb 1-1: device descriptor read/64, error -71 Mar 25 12:11:48 volumio-5-living-room kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Mar 25 12:11:48 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:48 volumio-5-living-room volumio[1420]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4/4-1/4-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-partuuid/6eb2ab98-9258-40ef-86c4-ae3563d7fc0a /dev/disk/by-uuid/FEF28CA5F28C63AD /dev/disk/by-path/platform-xhci-hcd.1-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-label/Volumio_V01 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-id/usb-Seagate_Basic_00000000NABCBD6F-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4/4-1/4-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"Volumio_V01","ID_FS_LABEL_ENC":"Volumio_V01","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"FEF28CA5F28C63AD","ID_FS_UUID_ENC":"FEF28CA5F28C63AD","ID_INSTANCE":"0:0","ID_MODEL":"Basic","ID_MODEL_ENC":"Basic\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"aa14","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"9767276544","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"6eb2ab98-9258-40ef-86c4-ae3563d7fc0a","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"b97b3c06-72fb-4852-9ad5-d0ec52e60386","ID_PATH":"platform-xhci-hcd.1-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_1-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"9340","ID_SERIAL":"Seagate_Basic_00000000NABCBD6F-0:0","ID_SERIAL_SHORT":"00000000NABCBD6F","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"Basic","ID_USB_MODEL_ENC":"Basic\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"aa14","ID_USB_REVISION":"9340","ID_USB_SERIAL":"Seagate_Basic_00000000NABCBD6F-0:0","ID_USB_SERIAL_SHORT":"00000000NABCBD6F","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Seagate","ID_USB_VENDOR_ENC":"Seagate\\x20","ID_USB_VENDOR_ID":"0bc2","ID_VENDOR":"Seagate","ID_VENDOR_ENC":"Seagate\\x20","ID_VENDOR_ID":"0bc2","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"4526","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3117558"} Mar 25 12:11:48 volumio-5-living-room sudo[8588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Mar 25 12:11:48 volumio-5-living-room sudo[8588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:48 volumio-5-living-room ntfs-3g[1793]: Unmounting /dev/sda2 (Volumio_V01) Mar 25 12:11:48 volumio-5-living-room ntfs-3g[1793]: Failed to sync device /dev/sda2: Input/output error Mar 25 12:11:48 volumio-5-living-room sudo[8588]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:48 volumio-5-living-room systemd[1]: media-Volumio_V01.mount: Deactivated successfully. Mar 25 12:11:48 volumio-5-living-room volumio[1420]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4/4-1/4-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-xhci-hcd.1-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-id/usb-Seagate_Basic_00000000NABCBD6F-0:0-part1 /dev/disk/by-partuuid/8d5a8dbf-e5ea-4948-aa56-9239ae3135ee","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4/4-1/4-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Basic","ID_MODEL_ENC":"Basic\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"aa14","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"8d5a8dbf-e5ea-4948-aa56-9239ae3135ee","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"b97b3c06-72fb-4852-9ad5-d0ec52e60386","ID_PATH":"platform-xhci-hcd.1-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_1-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"9340","ID_SERIAL":"Seagate_Basic_00000000NABCBD6F-0:0","ID_SERIAL_SHORT":"00000000NABCBD6F","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"Basic","ID_USB_MODEL_ENC":"Basic\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"aa14","ID_USB_REVISION":"9340","ID_USB_SERIAL":"Seagate_Basic_00000000NABCBD6F-0:0","ID_USB_SERIAL_SHORT":"00000000NABCBD6F","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Seagate","ID_USB_VENDOR_ENC":"Seagate\\x20","ID_USB_VENDOR_ID":"0bc2","ID_VENDOR":"Seagate","ID_VENDOR_ENC":"Seagate\\x20","ID_VENDOR_ID":"0bc2","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"4525","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3020122"} Mar 25 12:11:48 volumio-5-living-room volumio[1420]: error: Cannot associate FS Label, not mounting Mar 25 12:11:48 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:48 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:48 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:48 volumio-5-living-room ntfs-3g[1793]: Failed to close volume /dev/sda2: Input/output error Mar 25 12:11:48 volumio-5-living-room sudo[8599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:48 volumio-5-living-room sudo[8599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:48 volumio-5-living-room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 25 12:11:48 volumio-5-living-room sudo[8599]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Executing endpoint tc_getconfig Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 25 12:11:49 volumio-5-living-room vtcs[8602]: STARTING TidalConnect services, version: 1.6.1 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:49 volumio-5-living-room vtcs[8602]: STARTED TidalConnect services. Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Executing endpoint tc_connect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Connecting to TidalConnect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 25 12:11:49 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 25 12:11:49 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.106Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.106Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.106Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.106Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.106Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.107Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:49 volumio-5-living-room sudo[8627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:49 volumio-5-living-room sudo[8627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room sudo[8627]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: device descriptor read/64, error -71 Mar 25 12:11:49 volumio-5-living-room sudo[8639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:49 volumio-5-living-room sudo[8639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room sudo[8639]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 6 using xhci-hcd Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: New USB device found, idVendor=17ae, idProduct=0010, bcdDevice= 1.00 Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: Product: NODE ICON Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: Manufacturer: Bluesound Mar 25 12:11:49 volumio-5-living-room kernel: usb 1-1: SerialNumber: 9056821c196e Mar 25 12:11:49 volumio-5-living-room kernel: input: Bluesound NODE ICON as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:17AE:0010.0004/input/input8 Mar 25 12:11:49 volumio-5-living-room kernel: hid-generic 0003:17AE:0010.0004: input,hidraw0: USB HID v1.01 Device [Bluesound NODE ICON] on usb-xhci-hcd.0-1/input2 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:49 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Detached Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 25 12:11:49 volumio-5-living-room (udev-worker)[8292]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:49 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Attached Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Preparing to save Alsa Options, stopping services first Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPause Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pause Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NODE ICON","alsacard":"ICON"},"i2s":false} Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Setting mixer Playback Volume for card NODE ICON Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room vtcs[8602]: [2026-03-25 12:11:49.780] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 25 12:11:49 volumio-5-living-room vtcs[8602]: [2026-03-25 12:11:49.782] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 25 12:11:49 volumio-5-living-room sudo[8664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:49 volumio-5-living-room sudo[8664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating Volume Controller Parameters: Device: 5 Name: NODE ICON Mixer: Playback Volume Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Disabling external Volume Control Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:11:49 volumio-5-living-room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 25 12:11:49 volumio-5-living-room systemd[1]: vtcs.service: Killing process 8606 (vtcs) with signal SIGKILL. Mar 25 12:11:49 volumio-5-living-room systemd[1]: vtcs.service: Deactivated successfully. Mar 25 12:11:49 volumio-5-living-room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 25 12:11:49 volumio-5-living-room sudo[8664]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room sudo[8673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:49 volumio-5-living-room sudo[8673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::getcurrentVolume Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:11:49 volumio-5-living-room sudo[8680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:49 volumio-5-living-room sudo[8680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:49 volumio-5-living-room sudo[8673]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:49 volumio-5-living-room sudo[8680]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.880Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.880Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room sudo[8690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:49 volumio-5-living-room sudo[8690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 12:11:49 volumio-5-living-room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:11:49 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:49.900 [8561.8561] INFO SampleApp: Stopping Local configuration server Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreStateMachine::updateTrackBlock Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrackBlock Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.934Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:49 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:49.934Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Asound.conf file unchanged, so no further update is needed Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:11:49 volumio-5-living-room sudo[8705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:49 volumio-5-living-room sudo[8705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:49 volumio-5-living-room sudo[8707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:11:49 volumio-5-living-room sudo[8707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room sudo[8707]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:49 volumio-5-living-room sudo[8712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:11:49 volumio-5-living-room sudo[8712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:49 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 25 12:11:50 volumio-5-living-room sudo[8721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:50 volumio-5-living-room sudo[8721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8705]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room systemd[1]: musicservicesshield.service: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 25 12:11:50 volumio-5-living-room sudo[8723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:50 volumio-5-living-room sudo[8732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:50 volumio-5-living-room sudo[8723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.051Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.051Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:11:50 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 25 12:11:50 volumio-5-living-room systemd[1]: vtcs.service: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room sudo[8742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:11:50 volumio-5-living-room sudo[8742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Asound.conf file written Mar 25 12:11:50 volumio-5-living-room sudo[8732]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:50 volumio-5-living-room sudo[8746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 25 12:11:50 volumio-5-living-room sudo[8748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8748]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8721]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8723]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8743]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:11:50 volumio-5-living-room sudo[8743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:11:50 volumio-5-living-room sudo[8743]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:11:50 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card ICON Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Found hardware: "USB-Audio" "USB Mixer" "USB17ae:0010" "" "" Mar 25 12:11:50 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:11:50 volumio-5-living-room volumio[1420]: No state is present for card ICON Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:50 volumio-5-living-room sudo[8759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:11:50 volumio-5-living-room sudo[8759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8759]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:11:50 volumio-5-living-room sudo[8761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:11:50 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:50 volumio-5-living-room systemd[1]: shairport-sync.service: Consumed 1.525s CPU time. Mar 25 12:11:50 volumio-5-living-room sudo[8771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:50 volumio-5-living-room sudo[8771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:50 volumio-5-living-room sudo[8742]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:50 volumio-5-living-room sudo[8775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:11:50 volumio-5-living-room sudo[8775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:11:50 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:11:50 volumio-5-living-room sudo[8781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:11:50 volumio-5-living-room sudo[8781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:11:50 volumio-5-living-room sudo[8771]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8781]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:11:50 volumio-5-living-room sudo[8801]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:11:50 volumio-5-living-room sudo[8801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:11:50 volumio-5-living-room sudo[8775]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room sudo[8818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:50 volumio-5-living-room sudo[8818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:11:50 volumio-5-living-room sudo[8814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:11:50 volumio-5-living-room sudo[8816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:50 volumio-5-living-room sudo[8801]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:11:50 volumio-5-living-room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 25 12:11:50 volumio-5-living-room sudo[8818]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:50 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:11:50 volumio-5-living-room sudo[8816]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Executing endpoint tc_getconfig Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 25 12:11:50 volumio-5-living-room vtcs[8826]: STARTING TidalConnect services, version: 1.6.1 Mar 25 12:11:50 volumio-5-living-room vtcs[8826]: STARTED TidalConnect services. Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Executing endpoint tc_connect Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: Connecting to TidalConnect Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 25 12:11:50 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 25 12:11:50 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.564Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.565Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::getcurrentVolume Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::updateTrackBlock Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrackBlock Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.769Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.769Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:50 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:50 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:50.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:50 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:51 volumio-5-living-room mpd[8824]: 2026-03-25T12:11:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 25 12:11:51 volumio-5-living-room systemd[1]: Started mpd.service - Music Player Daemon. Mar 25 12:11:51 volumio-5-living-room sudo[8712]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:51 volumio-5-living-room sudo[8761]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:51 volumio-5-living-room volumio[1420]: error: MPD error: The expression evaluated to a falsy value: Mar 25 12:11:51 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:11:51 volumio-5-living-room volumio[1420]: error: The expression evaluated to a falsy value: Mar 25 12:11:51 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:11:51 volumio-5-living-room volumio[1420]: error: updateQueue error: null Mar 25 12:11:51 volumio-5-living-room systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:11:51 volumio-5-living-room bash[8871]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 36: cset: command not found Mar 25 12:11:51 volumio-5-living-room bash[8872]: /data/plugins/system_hardware/music_services_shield/moveprocess.sh: 5: cset: not found Mar 25 12:11:51 volumio-5-living-room bash[8876]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 49: cset: command not found Mar 25 12:11:51 volumio-5-living-room systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8561]: 20260325 12:11:51.685 [8561.8561] INFO SampleApp: shat down connection on UNIX socket Mar 25 12:11:51 volumio-5-living-room systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 25 12:11:51 volumio-5-living-room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:11:51 volumio-5-living-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:11:51 volumio-5-living-room sudo[8814]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:51 volumio-5-living-room sudo[8746]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:51 volumio-5-living-room sudo[8690]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: Executing endpoint qc_getconfig Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.734 [8877.8877] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.736 [8877.8877] INFO VolumeManager: [0x20f6e68]: Setting new playback volume: 75 Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.736 [8877.8877] INFO VolumeManager: [0x20f6e68]: Setting new mute state: 0 Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.736 [8877.8877] INFO AudioStreamManager: [0x20f6bc0]: Setting new audio download buffer size: 1048576 Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.736 [8877.8877] INFO QobuzConnect: [0x20f7730]: Client initialized! Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.736 [8877.8877] INFO SampleApp: Starting Avahi advertising, name: Volumio 5 Living Room, service name: _qobuz-connect._tcp Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.744 [8877.8877] INFO LocalConfigManager: [0x20f68e8]: Starting Local Configuration server Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.744 [8877.8877] INFO SampleApp: Starting Local configuration server Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.744 [8877.8877] INFO SampleApp: Connected to UNIX socket client 0x20e1818 Mar 25 12:11:51 volumio-5-living-room qobuz-connect[8877]: 20260325 12:11:51.884 [8877.8877] INFO SampleApp: Playback volume changed: 75 Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:51 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:52 volumio-5-living-room sudo[8892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:52 volumio-5-living-room sudo[8892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:52 volumio-5-living-room sudo[8892]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:52 volumio-5-living-room volumio[1420]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Volumio_V01 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:53 volumio-5-living-room sudo[8904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:53 volumio-5-living-room sudo[8904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:53 volumio-5-living-room sudo[8904]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:11:53 volumio-5-living-room sudo[8916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:11:53 volumio-5-living-room sudo[8916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:11:53 volumio-5-living-room sudo[8916]: pam_unix(sudo:session): session closed for user root Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreStateMachine::getcurrentVolume Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreStateMachine::updateTrackBlock Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrackBlock Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 12:11:53 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:53.829Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:53 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:53.830Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:53 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:53 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:53.845Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:53 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:53.845Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:53 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Scanning removed location : "USB/Volumio_V01" Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:53 volumio-5-living-room volumio[1420]: ---------------------------- MPD announces state update: update Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::getState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:53 volumio-5-living-room volumio[1420]: ---------------------------- MPD announces state update: update Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::getState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: MPD Database updated - AlbumList cache refreshed Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:53 volumio-5-living-room volumio[1420]: ---------------------------- MPD announces state update: database Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:53 volumio-5-living-room volumio[1420]: ---------------------------- MPD announces state update: update Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::getState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::getState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: MPD Database updated - AlbumList cache refreshed Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:53 volumio-5-living-room volumio[1420]: ---------------------------- MPD announces state update: database Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Mar 25 12:11:53 volumio-5-living-room volumio[1420]: ---------------------------- MPD announces state update: update Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 25 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::getState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::getState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::sendMpdCommand status Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 24 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 24 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 24 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 3 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 2 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 2 milliseconds Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Command Router : Notfying DB Updatefalse Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Command Router : Notfying DB Updatefalse Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: Command Router : Notfying DB Updatefalse Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: ControllerMpd::pushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:53 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ControllerMpd::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ControllerMpd::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ControllerMpd::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.018Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.018Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.018Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.019Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.020Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.021Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.021Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.021Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.021Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.021Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.021Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ------------------------------ 115ms Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ------------------------------ 114ms Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ------------------------------ 93ms Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ------------------------------ 94ms Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 94 milliseconds Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 92 milliseconds Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: sendMpdCommand status took 92 milliseconds Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: Command Router : Notfying DB Updatefalse Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: ControllerMpd::parseState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ControllerMpd::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ControllerMpd::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::servicePushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 12:11:54 volumio-5-living-room volumio[1420]: verbose: CURRENT POSITION 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState stateService stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::syncState currentStatus stop Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: No code Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.046Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:11:54 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:11:54.047Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ------------------------------ 115ms Mar 25 12:11:54 volumio-5-living-room volumio[1420]: info: ------------------------------ 115ms Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:54 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:11:55 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:56 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:56 volumio-5-living-room volumio[1420]: info: TidalConnect service started! Mar 25 12:11:59 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:59 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:59 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:59 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:59 volumio-5-living-room volumio[1420]: error: error Mar 25 12:11:59 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:00 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:01 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:01 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:02 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:02 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:03 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:03 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:03 volumio-5-living-room volumio[1420]: error: error Mar 25 12:12:35 volumio-5-living-room kernel: usb 1-1: USB disconnect, device number 6 Mar 25 12:12:35 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 7 using xhci-hcd Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: New USB device found, idVendor=17ae, idProduct=0010, bcdDevice= 1.00 Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: Product: NODE ICON Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: Manufacturer: Bluesound Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: SerialNumber: 9056821c196e Mar 25 12:12:36 volumio-5-living-room kernel: input: Bluesound NODE ICON as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:17AE:0010.0005/input/input9 Mar 25 12:12:36 volumio-5-living-room kernel: hid-generic 0003:17AE:0010.0005: input,hidraw0: USB HID v1.01 Device [Bluesound NODE ICON] on usb-xhci-hcd.0-1/input2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Mar 25 12:12:36 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Detached Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 25 12:12:36 volumio-5-living-room (udev-worker)[9015]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Mar 25 12:12:36 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Attached Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Preparing to save Alsa Options, stopping services first Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPause Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pause Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NODE ICON","alsacard":"ICON"},"i2s":false} Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: USB disconnect, device number 7 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Invalid card number '5'. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Updating Volume Controller Parameters: Device: 5 Name: NODE ICON Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Disabling external Volume Control Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Invalid card number '5'. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=undefined Mute =false Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:12:36.283Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:12:36 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:12:36.283Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:12:36 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Asound.conf file unchanged, so no further update is needed Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:36 volumio-5-living-room sudo[9052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:12:36 volumio-5-living-room sudo[9052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9052]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:12:36 volumio-5-living-room sudo[9054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room vtcs[8826]: [2026-03-25 12:12:36.318] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 25 12:12:36 volumio-5-living-room vtcs[8826]: [2026-03-25 12:12:36.318] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 25 12:12:36 volumio-5-living-room vtcs[8826]: [2026-03-25 12:12:36.318] [tisoc] [error] [SpkconServer.cpp:476] socket write error. fd=3 errorno=32 error=Broken pipe Mar 25 12:12:36 volumio-5-living-room systemd[1]: musicservicesshield.service: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 25 12:12:36 volumio-5-living-room sudo[9064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:36 volumio-5-living-room sudo[9064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:36 volumio-5-living-room sudo[9066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:12:36 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:12:36 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room sudo[9075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:36 volumio-5-living-room sudo[9075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:36 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:12:36 volumio-5-living-room sudo[9083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:12:36 volumio-5-living-room sudo[9083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Asound.conf file written Mar 25 12:12:36 volumio-5-living-room sudo[9075]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 25 12:12:36 volumio-5-living-room sudo[9088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 25 12:12:36 volumio-5-living-room systemd[1]: vtcs.service: Killing process 8844 (vtcs) with signal SIGKILL. Mar 25 12:12:36 volumio-5-living-room systemd[1]: vtcs.service: Killing process 8845 (vtcs) with signal SIGKILL. Mar 25 12:12:36 volumio-5-living-room systemd[1]: vtcs.service: Killing process 8847 (vtcs) with signal SIGKILL. Mar 25 12:12:36 volumio-5-living-room systemd[1]: vtcs.service: Killing process 8848 (vtcs) with signal SIGKILL. Mar 25 12:12:36 volumio-5-living-room systemd[1]: vtcs.service: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 25 12:12:36 volumio-5-living-room sudo[9088]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:36 volumio-5-living-room sudo[9092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9066]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:36 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:36 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:12:36 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Mar 25 12:12:36 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:36 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:12:36 volumio-5-living-room sudo[9064]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9084]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:12:36 volumio-5-living-room sudo[9084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:12:36 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:36 volumio-5-living-room systemd[1]: shairport-sync.service: Consumed 1.546s CPU time. Mar 25 12:12:36 volumio-5-living-room sudo[9084]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:36 volumio-5-living-room sudo[9097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9097]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:12:36 volumio-5-living-room sudo[9102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room qobuz-connect[8877]: 20260325 12:12:36.480 [8877.8877] INFO SampleApp: Stopping Local configuration server Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 25 12:12:36 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:36 volumio-5-living-room sudo[9083]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room sudo[9113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:36 volumio-5-living-room sudo[9113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:12:36 volumio-5-living-room sudo[9115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:36 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room sudo[9115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 8 using xhci-hcd Mar 25 12:12:36 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:12:36 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room sudo[9127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:36 volumio-5-living-room sudo[9127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:12:36 volumio-5-living-room sudo[9127]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:12:36 volumio-5-living-room sudo[9147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:36 volumio-5-living-room sudo[9148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9115]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9113]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9139]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:12:36 volumio-5-living-room sudo[9139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:12:36 volumio-5-living-room sudo[9139]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:12:36 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:12:36 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: New USB device found, idVendor=17ae, idProduct=0010, bcdDevice= 1.00 Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: Product: NODE ICON Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: Manufacturer: Bluesound Mar 25 12:12:36 volumio-5-living-room kernel: usb 1-1: SerialNumber: 9056821c196e Mar 25 12:12:36 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:36 volumio-5-living-room sudo[9147]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:12:36 volumio-5-living-room kernel: input: Bluesound NODE ICON as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:17AE:0010.0006/input/input10 Mar 25 12:12:36 volumio-5-living-room kernel: hid-generic 0003:17AE:0010.0006: input,hidraw0: USB HID v1.01 Device [Bluesound NODE ICON] on usb-xhci-hcd.0-1/input2 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Mar 25 12:12:36 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Detached Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 25 12:12:36 volumio-5-living-room (udev-worker)[9013]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Mar 25 12:12:36 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Attached Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Preparing to save Alsa Options, stopping services first Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPause Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pause Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NODE ICON","alsacard":"ICON"},"i2s":false} Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Setting mixer Playback Volume for card NODE ICON Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:36 volumio-5-living-room sudo[9194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:36 volumio-5-living-room sudo[9194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Updating Volume Controller Parameters: Device: 5 Name: NODE ICON Mixer: Playback Volume Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Disabling external Volume Control Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:36 volumio-5-living-room sudo[9202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:36 volumio-5-living-room sudo[9202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:36 volumio-5-living-room sudo[9208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:36 volumio-5-living-room sudo[9194]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:36 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:36 volumio-5-living-room sudo[9208]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:36 volumio-5-living-room sudo[9202]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:12:37 volumio-5-living-room sudo[9211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:37 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:12:37.018Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:12:37 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:12:37.018Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:12:37 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Asound.conf file unchanged, so no further update is needed Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room sudo[9223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:12:37 volumio-5-living-room sudo[9223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9223]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:12:37 volumio-5-living-room sudo[9226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:12:37 volumio-5-living-room sudo[9226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:37 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room sudo[9235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:12:37 volumio-5-living-room sudo[9235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:12:37 volumio-5-living-room sudo[9237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:37 volumio-5-living-room sudo[9237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:37 volumio-5-living-room sudo[9255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:12:37 volumio-5-living-room sudo[9255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Asound.conf file written Mar 25 12:12:37 volumio-5-living-room sudo[9245]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:12:37 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:37 volumio-5-living-room sudo[9259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 25 12:12:37 volumio-5-living-room sudo[9260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room kernel: usb 1-1: USB disconnect, device number 8 Mar 25 12:12:37 volumio-5-living-room sudo[9260]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9253]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:12:37 volumio-5-living-room sudo[9253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:12:37 volumio-5-living-room sudo[9253]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9235]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room sudo[9270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:12:37 volumio-5-living-room sudo[9270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9270]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:12:37 volumio-5-living-room sudo[9272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:37 volumio-5-living-room sudo[9282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:37 volumio-5-living-room sudo[9282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9237]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:37 volumio-5-living-room sudo[9284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room sudo[9293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:37 volumio-5-living-room sudo[9302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:12:37 volumio-5-living-room sudo[9302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Asound.conf file written Mar 25 12:12:37 volumio-5-living-room sudo[9306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 25 12:12:37 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:37 volumio-5-living-room sudo[9306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9255]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9306]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card vc4hdmi1 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:12:37 volumio-5-living-room volumio[1420]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Mar 25 12:12:37 volumio-5-living-room volumio[1420]: Hardware is initialized using a generic method Mar 25 12:12:37 volumio-5-living-room volumio[1420]: No state is present for card Loopback Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room sudo[9325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:12:37 volumio-5-living-room sudo[9325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9325]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9293]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9282]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:12:37 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:12:37 volumio-5-living-room sudo[9327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:37 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:37 volumio-5-living-room sudo[9343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:37 volumio-5-living-room sudo[9343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:37 volumio-5-living-room sudo[9347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9284]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 25 12:12:37 volumio-5-living-room volumio[1420]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room sudo[9339]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:12:37 volumio-5-living-room sudo[9339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:12:37 volumio-5-living-room sudo[9339]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:12:37 volumio-5-living-room sudo[9364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:12:37 volumio-5-living-room sudo[9364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room sudo[9353]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:37 volumio-5-living-room sudo[9366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:37 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:37 volumio-5-living-room sudo[9302]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:12:37 volumio-5-living-room sudo[9364]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Shairport-Sync Started Mar 25 12:12:37 volumio-5-living-room sudo[9343]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9347]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:12:37 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:12:37 volumio-5-living-room sudo[9386]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:12:37 volumio-5-living-room sudo[9386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:12:37 volumio-5-living-room sudo[9386]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room qobuz-connect[8877]: 20260325 12:12:37.793 [8877.8877] INFO SampleApp: shat down connection on UNIX socket Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:37 volumio-5-living-room systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:12:37 volumio-5-living-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:12:37 volumio-5-living-room sudo[9211]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9259]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9335]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9148]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9366]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room sudo[9092]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: Executing endpoint qc_getconfig Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.836 [9389.9389] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.839 [9389.9389] INFO VolumeManager: [0x1fd6e68]: Setting new playback volume: 75 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.840 [9389.9389] INFO VolumeManager: [0x1fd6e68]: Setting new mute state: 0 Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.840 [9389.9389] INFO AudioStreamManager: [0x1fd6bc0]: Setting new audio download buffer size: 1048576 Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.840 [9389.9389] INFO QobuzConnect: [0x1fd7730]: Client initialized! Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.840 [9389.9389] INFO SampleApp: Starting Avahi advertising, name: Volumio 5 Living Room, service name: _qobuz-connect._tcp Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.851 [9389.9389] INFO LocalConfigManager: [0x1fd68e8]: Starting Local Configuration server Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.851 [9389.9389] INFO SampleApp: Starting Local configuration server Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.851 [9389.9389] INFO SampleApp: Connected to UNIX socket client 0x1fc1818 Mar 25 12:12:37 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:37.987 [9389.9389] INFO SampleApp: Playback volume changed: 75 Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:37 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:38 volumio-5-living-room kernel: usb usb1-port1: Cannot enable. Maybe the USB cable is bad? Mar 25 12:12:38 volumio-5-living-room mpd[9388]: 2026-03-25T12:12:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 25 12:12:38 volumio-5-living-room systemd[1]: Started mpd.service - Music Player Daemon. Mar 25 12:12:38 volumio-5-living-room sudo[9327]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:38 volumio-5-living-room sudo[9226]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:38 volumio-5-living-room sudo[9272]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:38 volumio-5-living-room sudo[9054]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:38 volumio-5-living-room sudo[9102]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: MPD error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: MPD error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: MPD error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: MPD error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: The expression evaluated to a falsy value: Mar 25 12:12:38 volumio-5-living-room volumio[1420]: assert.ok(self.idling) Mar 25 12:12:38 volumio-5-living-room volumio[1420]: error: updateQueue error: null Mar 25 12:12:38 volumio-5-living-room systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:12:38 volumio-5-living-room bash[9405]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 36: cset: command not found Mar 25 12:12:38 volumio-5-living-room bash[9406]: /data/plugins/system_hardware/music_services_shield/moveprocess.sh: 5: cset: not found Mar 25 12:12:38 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 10 using xhci-hcd Mar 25 12:12:38 volumio-5-living-room bash[9410]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 49: cset: command not found Mar 25 12:12:38 volumio-5-living-room systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:12:38 volumio-5-living-room kernel: usb 1-1: Device not responding to setup address. Mar 25 12:12:38 volumio-5-living-room kernel: usb 1-1: Device not responding to setup address. Mar 25 12:12:38 volumio-5-living-room kernel: usb 1-1: device not accepting address 10, error -71 Mar 25 12:12:38 volumio-5-living-room kernel: usb usb1-port1: attempt power cycle Mar 25 12:12:39 volumio-5-living-room kernel: usb 1-1: new high-speed USB device number 11 using xhci-hcd Mar 25 12:12:39 volumio-5-living-room kernel: usb 1-1: New USB device found, idVendor=17ae, idProduct=0010, bcdDevice= 1.00 Mar 25 12:12:39 volumio-5-living-room kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 12:12:39 volumio-5-living-room kernel: usb 1-1: Product: NODE ICON Mar 25 12:12:39 volumio-5-living-room kernel: usb 1-1: Manufacturer: Bluesound Mar 25 12:12:39 volumio-5-living-room kernel: usb 1-1: SerialNumber: 9056821c196e Mar 25 12:12:39 volumio-5-living-room kernel: input: Bluesound NODE ICON as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:17AE:0010.0007/input/input11 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:12:39 volumio-5-living-room sudo[9420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:12:39 volumio-5-living-room sudo[9420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room kernel: hid-generic 0003:17AE:0010.0007: input,hidraw0: USB HID v1.01 Device [Bluesound NODE ICON] on usb-xhci-hcd.0-1/input2 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Mar 25 12:12:39 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Detached Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:39 volumio-5-living-room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 25 12:12:39 volumio-5-living-room sudo[9420]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 25 12:12:39 volumio-5-living-room (udev-worker)[9013]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Mar 25 12:12:39 volumio-5-living-room volumio[1420]: ---------------------------- USB Audio Device Attached Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::Close All Modals sent Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Preparing to save Alsa Options, stopping services first Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPause Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pause Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NODE ICON","alsacard":"ICON"},"i2s":false} Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Setting mixer Playback Volume for card NODE ICON Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:39 volumio-5-living-room sudo[9448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:39 volumio-5-living-room sudo[9448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Updating Volume Controller Parameters: Device: 5 Name: NODE ICON Mixer: Playback Volume Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Disabling external Volume Control Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:39 volumio-5-living-room systemd[1]: vtcs.service: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 25 12:12:39 volumio-5-living-room sudo[9448]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room sudo[9456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:39 volumio-5-living-room sudo[9456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:39 volumio-5-living-room sudo[9463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:39 volumio-5-living-room sudo[9463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room sudo[9456]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: TidalConnect service stoped! Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Executing endpoint tc_getconfig Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 25 12:12:39 volumio-5-living-room sudo[9463]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room sudo[9471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:39 volumio-5-living-room sudo[9471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 12:12:39 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:39.709 [9389.9389] INFO SampleApp: Stopping Local configuration server Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: VolumeController:: Volume=51 Mute =false Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreStateMachine::pushState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioPushState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output update for this device Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: MRS: Pushing multiroomSync output Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:39 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:12:39.724Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" state=STATUS_STOPPED positionMs=0 volume=51 Mar 25 12:12:39 volumio-5-living-room volumio5-onboarding[2077]: time=2026-03-25T11:12:39.724Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.22:41644,00:00:00:00:00:00%01 @ 0x2cac540" id= title= Mar 25 12:12:39 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Asound.conf file unchanged, so no further update is needed Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting MPD Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Output device has changed, restarting Shairport Sync Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:39 volumio-5-living-room sudo[9484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 12:12:39 volumio-5-living-room sudo[9484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room sudo[9484]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room sudo[9486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 12:12:39 volumio-5-living-room sudo[9486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room systemd[1]: musicservicesshield.service: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:39 volumio-5-living-room sudo[9496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:39 volumio-5-living-room sudo[9496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room systemd[1]: mpd.service: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 12:12:39 volumio-5-living-room systemd[1]: mpd.socket: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 12:12:39 volumio-5-living-room sudo[9498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 25 12:12:39 volumio-5-living-room sudo[9498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room sudo[9507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 25 12:12:39 volumio-5-living-room sudo[9507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: MPD Permissions set Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 12:12:39 volumio-5-living-room sudo[9509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 25 12:12:39 volumio-5-living-room sudo[9509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Starting Shairport Sync Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Preparing to generate the ALSA configuration file Mar 25 12:12:39 volumio-5-living-room sudo[9507]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room sudo[9498]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room sudo[9520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 12:12:39 volumio-5-living-room sudo[9520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room qobuz-connect[9389]: 20260325 12:12:39.863 [9389.9389] INFO SampleApp: shat down connection on UNIX socket Mar 25 12:12:39 volumio-5-living-room sudo[9496]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room sudo[9522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:12:39 volumio-5-living-room sudo[9522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: Reading ALSA contributions from plugins. Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: QobuzConnect: setDeactiveState invoked Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CoreCommandRouter::volumioGetState Mar 25 12:12:39 volumio-5-living-room volumio[1420]: info: CorePlayQueue::getTrack 0 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 12:12:39 volumio-5-living-room volumio[1420]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Mar 25 12:12:39 volumio-5-living-room volumio[1420]: Job for vtcs.service canceled. Mar 25 12:12:39 volumio-5-living-room volumio[1420]: at ChildProcess.exithandler (node:child_process:421:12) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: at ChildProcess.emit (node:events:514:28) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: at maybeClose (node:internal/child_process:1105:16) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: at Socket. (node:internal/child_process:457:11) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: at Socket.emit (node:events:514:28) Mar 25 12:12:39 volumio-5-living-room volumio[1420]: at Pipe. (node:net:337:12) { Mar 25 12:12:39 volumio-5-living-room volumio[1420]: code: 1, Mar 25 12:12:39 volumio-5-living-room volumio[1420]: killed: false, Mar 25 12:12:39 volumio-5-living-room volumio[1420]: signal: null, Mar 25 12:12:39 volumio-5-living-room volumio[1420]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Mar 25 12:12:39 volumio-5-living-room volumio[1420]: stdout: '', Mar 25 12:12:39 volumio-5-living-room volumio[1420]: stderr: 'Job for vtcs.service canceled.\n' Mar 25 12:12:39 volumio-5-living-room volumio[1420]: } Mar 25 12:12:39 volumio-5-living-room volumio[1420]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 12:12:39 volumio-5-living-room sudo[9514]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 12:12:39 volumio-5-living-room sudo[9514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 12:12:39 volumio-5-living-room sudo[9514]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 12:12:39 volumio-5-living-room sudo[9471]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 25 12:12:39 volumio-5-living-room systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:39 volumio-5-living-room sudo[9509]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room systemd[1]: shairport-sync.service: Consumed 1.516s CPU time. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 25 12:12:39 volumio-5-living-room sudo[9520]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:39 volumio-5-living-room systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:12:39 volumio-5-living-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 25 12:12:39 volumio-5-living-room sudo[9522]: pam_unix(sudo:session): session closed for user root Mar 25 12:12:40 volumio-5-living-room sudo[9560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 12:11' Mar 25 12:12:40 volumio-5-living-room sudo[9560]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"