Dec 21 11:53:00 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:10 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:10 rstream volumio[1191]: info: Listing playlists Dec 21 11:53:10 rstream volumio[1191]: info: Listing playlists Dec 21 11:53:20 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:30 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:30 rstream volumio[1191]: info: Listing playlists Dec 21 11:53:30 rstream volumio[1191]: info: Listing playlists Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:40 rstream volumio[1191]: verbose: New Socket.io Connection to 192.168.172.233 from 192.168.172.30 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 11 Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 11:53:40 rstream volumio[1191]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 21 11:53:40 rstream volumio[1191]: info: Received Get System Info Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 11:53:40 rstream volumio[1191]: info: Discovery: Getting this device information Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 11:53:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:40 rstream volumio[1191]: info: Listing playlists Dec 21 11:53:41 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 21 11:53:50 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:53:50 rstream volumio[1191]: info: Listing playlists Dec 21 11:53:50 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:00 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:09 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 11:54:09 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 11:54:10 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:10 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:10 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:17 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:19 rstream volumio[1191]: info: Getting Alsa Cards List without I2S DAC Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: Invalid card number '2'. Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 11:54:19 rstream volumio[1191]: info: Executing endpoint metavolumio Dec 21 11:54:19 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 21 11:54:20 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:30 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:30 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:30 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:40 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:49 rstream volumio[1191]: info: MRS: Updating multiroomSync output Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:50 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:50 rstream volumio[1191]: info: Listing playlists Dec 21 11:54:50 rstream volumio[1191]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 21 11:54:50 rstream volumio[1191]: info: Preparing to save Alsa Options, stopping services first Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:50 rstream volumio[1191]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:50 rstream volumio[1191]: info: Enabling PI HDMI Output Dec 21 11:54:50 rstream volumio[1191]: info: Disabling I2S DAC: Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:50 rstream volumio[1191]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Dec 21 11:54:50 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:50 rstream vtcs[1970]: [2025-12-21 11:54:50.714] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 21 11:54:50 rstream vtcs[1970]: [2025-12-21 11:54:50.714] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 21 11:54:50 rstream vtcs[1970]: [2025-12-21 11:54:50.714] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 21 11:54:50 rstream sudo[1615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:50 rstream sudo[1615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 11:54:50 rstream volumio[1191]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 11:54:50 rstream volumio[1191]: info: Disabling external Volume Control Dec 21 11:54:50 rstream volumio[1191]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 11:54:50 rstream volumio[1191]: info: Preparing to generate the ALSA configuration file Dec 21 11:54:50 rstream systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 21 11:54:50 rstream systemd[1]: vtcs.service: Deactivated successfully. Dec 21 11:54:50 rstream systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 21 11:54:50 rstream systemd[1]: vtcs.service: Consumed 25.943s CPU time. Dec 21 11:54:50 rstream sudo[1615]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:50 rstream sudo[1623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:50 rstream sudo[1623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:50 rstream volumio[1191]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 21 11:54:50 rstream volumio[1191]: info: Reading ALSA contributions from plugins. Dec 21 11:54:50 rstream volumio[1191]: info: Raspberry PI HDMI Output Enabled Dec 21 11:54:50 rstream sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:50 rstream sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:50 rstream sudo[1623]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:50 rstream sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Dec 21 11:54:50 rstream sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:50 rstream sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 21 11:54:50 rstream sudo[1632]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:50 rstream sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:50 rstream sudo[1635]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:50 rstream sudo[1642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:50 rstream sudo[1642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1639]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream volumio[1191]: info: No Overlays Loaded Dec 21 11:54:51 rstream systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 21 11:54:51 rstream qobuz-connect[1890]: 20251221 11:54:51.037 [1890.1890] INFO SampleApp: Stopping Local configuration server Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 11:54:51 rstream volumio[1191]: info: Alsa Settings successfully stored Dec 21 11:54:51 rstream volumio[1191]: info: VolumeController:: Volume=86 Mute =false Dec 21 11:54:51 rstream volumio[1191]: info: CoreStateMachine::pushState Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::volumioPushState Dec 21 11:54:51 rstream volumio[1191]: info: MRS: Pushing multiroomSync output update for this device Dec 21 11:54:51 rstream volumio[1191]: info: MRS: Pushing multiroomSync output Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:51 rstream volumio[1191]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 21 11:54:51 rstream volumio[1191]: SPOTIFY: SPOTIFY VOLUME undefined Dec 21 11:54:51 rstream volumio[1191]: SPOTIFY: VOLUMIO VOLUME 86 Dec 21 11:54:51 rstream volumio[1191]: info: Aligning Spotify Volume to Volumio Volume Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:51 rstream volumio[1191]: info: Setting Spotify Volume from Volumio: 86 Dec 21 11:54:51 rstream volumio[1191]: info: Asound.conf file written Dec 21 11:54:51 rstream sudo[1661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 21 11:54:51 rstream sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1661]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream volumio[1191]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 21 11:54:51 rstream volumio[1191]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 21 11:54:51 rstream volumio[1191]: info: Output device has changed, restarting MPD Dec 21 11:54:51 rstream volumio[1191]: info: Output device has changed, restarting Shairport Sync Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:51 rstream sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 11:54:51 rstream sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1667]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 11:54:51 rstream sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 11:54:51 rstream volumio[1191]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:51 rstream volumio[1191]: info: Output device has changed, restarting MPD Dec 21 11:54:51 rstream systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 21 11:54:51 rstream sudo[1679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:51 rstream sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 11:54:51 rstream sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: Output device has changed, restarting Shairport Sync Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:51 rstream sudo[1681]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream sudo[1686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 11:54:51 rstream sudo[1686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 11:54:51 rstream volumio[1191]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream systemd[1]: mpd.service: Deactivated successfully. Dec 21 11:54:51 rstream systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 11:54:51 rstream systemd[1]: mpd.service: Consumed 4.165s CPU time. Dec 21 11:54:51 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:51 rstream sudo[1695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:51 rstream sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1679]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream sudo[1697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:51 rstream sudo[1697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:51 rstream sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:51 rstream sudo[1709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: MRS: Updating multiroomSync output Dec 21 11:54:51 rstream sudo[1716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:51 rstream sudo[1716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: MPD Permissions set Dec 21 11:54:51 rstream volumio[1191]: info: MPD Permissions set Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream sudo[1716]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream sudo[1709]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream systemd[1]: mpd.socket: Deactivated successfully. Dec 21 11:54:51 rstream systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 11:54:51 rstream systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 11:54:51 rstream sudo[1719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:51 rstream sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:51 rstream sudo[1720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:51 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:51 rstream systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 11:54:51 rstream volumio[1191]: info: Starting Shairport Sync Dec 21 11:54:51 rstream systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 11:54:51 rstream volumio[1191]: info: Starting Shairport Sync Dec 21 11:54:51 rstream sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 11:54:51 rstream sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream volumio[1191]: info: Preparing to generate the ALSA configuration file Dec 21 11:54:51 rstream sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 11:54:51 rstream sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:51 rstream sudo[1695]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:51 rstream volumio[1191]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 21 11:54:51 rstream volumio[1191]: info: Reading ALSA contributions from plugins. Dec 21 11:54:51 rstream sudo[1702]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1697]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream volumio[1191]: info: Asound.conf file written Dec 21 11:54:52 rstream sudo[1761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 21 11:54:52 rstream sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream sudo[1761]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 11:54:52 rstream volumio[1191]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 21 11:54:52 rstream volumio[1191]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 21 11:54:52 rstream volumio[1191]: info: Output device has changed, restarting MPD Dec 21 11:54:52 rstream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 11:54:52 rstream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:52 rstream systemd[1]: shairport-sync.service: Consumed 3min 44.987s CPU time. Dec 21 11:54:52 rstream volumio[1191]: info: Output device has changed, restarting Shairport Sync Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:52 rstream sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 11:54:52 rstream sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream sudo[1768]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 11:54:52 rstream sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream qobuz-connect[1890]: 20251221 11:54:52.176 [1890.1890] INFO SampleApp: shat down connection on UNIX socket Dec 21 11:54:52 rstream volumio[1191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 11:54:52 rstream volumio[1191]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:52 rstream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:52 rstream sudo[1749]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 11:54:52 rstream sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 11:54:52 rstream sudo[1753]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream volumio[1191]: info: Preparing to generate the ALSA configuration file Dec 21 11:54:52 rstream sudo[1751]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 21 11:54:52 rstream systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 21 11:54:52 rstream systemd[1]: qobuz-connect.service: Consumed 1.072s CPU time. Dec 21 11:54:52 rstream sudo[1749]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:52 rstream sudo[1781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 21 11:54:52 rstream sudo[1642]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1719]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:52 rstream sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:52 rstream sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream sudo[1720]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream volumio[1191]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 21 11:54:52 rstream volumio[1191]: info: Reading ALSA contributions from plugins. Dec 21 11:54:52 rstream volumio[1191]: info: MPD Permissions set Dec 21 11:54:52 rstream volumio[1191]: info: Shairport-Sync Started Dec 21 11:54:52 rstream volumio[1191]: info: Shairport-Sync Started Dec 21 11:54:52 rstream systemd[1]: mpd.service: Deactivated successfully. Dec 21 11:54:52 rstream systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 11:54:52 rstream volumio[1191]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 21 11:54:52 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:52 rstream volumio[1191]: info: Executing endpoint qc_getconfig Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 21 11:54:52 rstream sudo[1792]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1781]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:52 rstream sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.543 [1785.1785] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.554 [1785.1785] INFO VolumeManager: [0x114d258]: Setting new playback volume: 75 Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.554 [1785.1785] INFO VolumeManager: [0x114d258]: Setting new mute state: 0 Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.554 [1785.1785] INFO QobuzConnect: [0x114dc28]: Client initialized! Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.555 [1785.1785] INFO SampleApp: Starting Avahi advertising, name: rStream, service name: _qobuz-connect._tcp Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:52 rstream systemd[1]: mpd.socket: Deactivated successfully. Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 11:54:52 rstream systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.595 [1785.1785] INFO LocalConfigManager: [0x114cb38]: Starting Local Configuration server Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.595 [1785.1785] INFO SampleApp: Starting Local configuration server Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.596 [1785.1785] INFO SampleApp: Connected to UNIX socket client 0x1137818 Dec 21 11:54:52 rstream volumio[1191]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 21 11:54:52 rstream volumio[1191]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 21 11:54:52 rstream volumio[1191]: info: Starting Shairport Sync Dec 21 11:54:52 rstream volumio[1191]: info: Asound.conf file unchanged, so no further update is needed Dec 21 11:54:52 rstream volumio[1191]: info: Output device has changed, restarting MPD Dec 21 11:54:52 rstream volumio[1191]: info: Output device has changed, restarting Shairport Sync Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:52 rstream sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 11:54:52 rstream systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 11:54:52 rstream sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 11:54:52 rstream sudo[1827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.693 [1785.1785] INFO SampleApp: Playback volume changed: 75 Dec 21 11:54:52 rstream sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream volumio[1191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 11:54:52 rstream volumio[1191]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream sudo[1827]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:52 rstream sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 11:54:52 rstream sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream sudo[1786]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 21 11:54:52 rstream sudo[1843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:52 rstream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 11:54:52 rstream qobuz-connect[1785]: 20251221 11:54:52.826 [1785.1785] INFO SampleApp: Stopping Local configuration server Dec 21 11:54:52 rstream sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 11:54:52 rstream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:52 rstream sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:52 rstream sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream volumio[1191]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Dec 21 11:54:52 rstream volumio[1191]: info: Sending Spotify command with payload to local API: /player/volume Dec 21 11:54:52 rstream sudo[1853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:52 rstream sudo[1853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:52 rstream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:52 rstream volumio[1191]: info: MPD Permissions set Dec 21 11:54:52 rstream sudo[1824]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:52 rstream systemd[1]: mpd.service: Deactivated successfully. Dec 21 11:54:52 rstream systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:52 rstream volumio[1191]: info: Shairport-Sync Started Dec 21 11:54:52 rstream volumio[1191]: info: MRS: Updating multiroomSync output Dec 21 11:54:53 rstream volumio[1191]: info: Starting Shairport Sync Dec 21 11:54:53 rstream sudo[1853]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:53 rstream systemd[1]: mpd.socket: Deactivated successfully. Dec 21 11:54:53 rstream systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 11:54:53 rstream systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 11:54:53 rstream sudo[1875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:53 rstream sudo[1875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:53 rstream sudo[1876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 11:54:53 rstream sudo[1876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:53 rstream systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 11:54:53 rstream systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 11:54:53 rstream sudo[1843]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:53 rstream sudo[1840]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:53 rstream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 11:54:53 rstream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 11:54:53 rstream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:53 rstream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:53 rstream sudo[1876]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:53 rstream volumio[1191]: info: Shairport-Sync Started Dec 21 11:54:53 rstream sudo[1880]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 11:54:53 rstream sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 11:54:53 rstream sudo[1880]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:53 rstream volumio[1191]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 21 11:54:53 rstream volumio[1191]: info: Preparing to save Alsa Options, stopping services first Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:53 rstream volumio[1191]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:53 rstream volumio[1191]: info: Enabling PI HDMI Output Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:53 rstream volumio[1191]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Dec 21 11:54:53 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 11:54:53 rstream volumio[1191]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 11:54:53 rstream volumio[1191]: info: Disabling external Volume Control Dec 21 11:54:53 rstream sudo[1913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:53 rstream sudo[1913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:53 rstream volumio[1191]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 11:54:53 rstream volumio[1191]: info: Preparing to generate the ALSA configuration file Dec 21 11:54:53 rstream sudo[1920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:53 rstream sudo[1920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:53 rstream volumio[1191]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 21 11:54:53 rstream volumio[1191]: info: Reading ALSA contributions from plugins. Dec 21 11:54:53 rstream volumio[1191]: info: MRS: Updating multiroomSync output Dec 21 11:54:53 rstream sudo[1913]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:53 rstream volumio[1191]: info: Raspberry PI HDMI Output Enabled Dec 21 11:54:53 rstream sudo[1926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:53 rstream volumio[1191]: info: TidalConnect service stoped! Dec 21 11:54:54 rstream sudo[1926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream volumio[1191]: info: VolumeController:: Volume=86 Mute =false Dec 21 11:54:54 rstream volumio[1191]: info: CoreStateMachine::pushState Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioPushState Dec 21 11:54:54 rstream volumio[1191]: info: MRS: Pushing multiroomSync output update for this device Dec 21 11:54:54 rstream volumio[1191]: info: MRS: Pushing multiroomSync output Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:54 rstream volumio[1191]: info: TidalConnect service stoped! Dec 21 11:54:54 rstream volumio[1191]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 21 11:54:54 rstream volumio[1191]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.uCvXNaclidfDlcNR3KCRtD9x9Oo1.1473e18a820d50636006b9dbf128e690.state.status' Dec 21 11:54:54 rstream sudo[1920]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream sudo[1930]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Dec 21 11:54:54 rstream sudo[1930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream sudo[1926]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream sudo[1930]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 11:54:54 rstream sudo[1941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:54 rstream sudo[1941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 11:54:54 rstream volumio[1191]: info: Alsa Settings successfully stored Dec 21 11:54:54 rstream volumio[1191]: info: Asound.conf file unchanged, so no further update is needed Dec 21 11:54:54 rstream volumio[1191]: info: Output device has changed, restarting MPD Dec 21 11:54:54 rstream volumio[1191]: info: Output device has changed, restarting Shairport Sync Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:54 rstream sudo[1955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 11:54:54 rstream sudo[1955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream sudo[1956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 11:54:54 rstream sudo[1955]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream volumio[1191]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 11:54:54 rstream volumio[1191]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:54 rstream systemd[1]: mpd.service: Deactivated successfully. Dec 21 11:54:54 rstream systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 11:54:54 rstream systemd[1]: mpd.service: Consumed 1.114s CPU time. Dec 21 11:54:54 rstream systemd[1]: mpd.socket: Deactivated successfully. Dec 21 11:54:54 rstream sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:54 rstream systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 11:54:54 rstream systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 11:54:54 rstream sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 11:54:54 rstream sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 11:54:54 rstream qobuz-connect[1785]: 20251221 11:54:54.609 [1785.1785] INFO SampleApp: shat down connection on UNIX socket Dec 21 11:54:54 rstream sudo[1977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 21 11:54:54 rstream sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 11:54:54 rstream volumio[1191]: info: MPD Permissions set Dec 21 11:54:54 rstream volumio[1191]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 21 11:54:54 rstream volumio[1191]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 21 11:54:54 rstream volumio[1191]: info: TidalConnect service stoped! Dec 21 11:54:54 rstream volumio[1191]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 21 11:54:54 rstream volumio[1191]: info: QobuzConnect: setDeactiveState invoked Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:54 rstream systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 21 11:54:54 rstream systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream sudo[1987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 21 11:54:54 rstream sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 11:54:54 rstream volumio[1191]: info: Starting Shairport Sync Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 21 11:54:54 rstream volumio[1191]: info: CoreStateMachine::getcurrentVolume Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 11:54:54 rstream sudo[1969]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream sudo[1977]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream sudo[1993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 21 11:54:54 rstream sudo[1993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream sudo[1991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 11:54:54 rstream sudo[1991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 11:54:54 rstream volumio[1191]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 21 11:54:54 rstream volumio[1191]: Job for vtcs.service canceled. Dec 21 11:54:54 rstream volumio[1191]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Dec 21 11:54:54 rstream systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 21 11:54:54 rstream sudo[1810]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream volumio[1191]: info: VolumeController:: Volume=86 Mute =false Dec 21 11:54:54 rstream volumio[1191]: info: CoreStateMachine::pushState Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioPushState Dec 21 11:54:54 rstream systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 21 11:54:54 rstream sudo[1941]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:54 rstream volumio[1191]: info: MRS: Pushing multiroomSync output update for this device Dec 21 11:54:54 rstream volumio[1191]: info: MRS: Pushing multiroomSync output Dec 21 11:54:54 rstream volumio[1191]: info: CoreCommandRouter::volumioGetState Dec 21 11:54:55 rstream volumio[1191]: info: CoreStateMachine::updateTrackBlock Dec 21 11:54:55 rstream volumio[1191]: info: CorePlayQueue::getTrackBlock Dec 21 11:54:55 rstream volumio[1191]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 11:54:55 rstream sudo[1875]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:55 rstream volumio[1191]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 21 11:54:55 rstream volumio[1191]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.uCvXNaclidfDlcNR3KCRtD9x9Oo1.1473e18a820d50636006b9dbf128e690.state.status' Dec 21 11:54:55 rstream sudo[1979]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 11:54:55 rstream sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 11:54:55 rstream sudo[1979]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:55 rstream volumio[1191]: info: TidalConnect service stoped! Dec 21 11:54:55 rstream volumio[1191]: info: TidalConnect service stoped! Dec 21 11:54:55 rstream sudo[1967]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:55 rstream sudo[1987]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:55 rstream volumio[1191]: info: TidalConnect service stoped! Dec 21 11:54:55 rstream volumio[1191]: info: Executing endpoint qc_getconfig Dec 21 11:54:55 rstream volumio[1191]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.153 [1992.1992] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.168 [1992.1992] INFO VolumeManager: [0x1f1c258]: Setting new playback volume: 75 Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.168 [1992.1992] INFO VolumeManager: [0x1f1c258]: Setting new mute state: 0 Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.168 [1992.1992] INFO QobuzConnect: [0x1f1cc28]: Client initialized! Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.168 [1992.1992] INFO SampleApp: Starting Avahi advertising, name: rStream, service name: _qobuz-connect._tcp Dec 21 11:54:55 rstream volumio[1191]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.203 [1992.1992] INFO LocalConfigManager: [0x1f1bb38]: Starting Local Configuration server Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.203 [1992.1992] INFO SampleApp: Starting Local configuration server Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.204 [1992.1992] INFO SampleApp: Connected to UNIX socket client 0x1f06818 Dec 21 11:54:55 rstream qobuz-connect[1992]: 20251221 11:54:55.216 [1992.1992] INFO SampleApp: Stopping Local configuration server Dec 21 11:54:55 rstream systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 21 11:54:55 rstream volumio[1191]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 21 11:54:55 rstream volumio[1191]: Job for vtcs.service canceled. Dec 21 11:54:55 rstream volumio[1191]: at ChildProcess.exithandler (node:child_process:421:12) Dec 21 11:54:55 rstream volumio[1191]: at ChildProcess.emit (node:events:514:28) Dec 21 11:54:55 rstream volumio[1191]: at maybeClose (node:internal/child_process:1105:16) Dec 21 11:54:55 rstream volumio[1191]: at Socket. (node:internal/child_process:457:11) Dec 21 11:54:55 rstream volumio[1191]: at Socket.emit (node:events:514:28) Dec 21 11:54:55 rstream volumio[1191]: at Pipe. (node:net:337:12) { Dec 21 11:54:55 rstream volumio[1191]: code: 1, Dec 21 11:54:55 rstream volumio[1191]: killed: false, Dec 21 11:54:55 rstream volumio[1191]: signal: null, Dec 21 11:54:55 rstream volumio[1191]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 21 11:54:55 rstream volumio[1191]: stdout: '', Dec 21 11:54:55 rstream volumio[1191]: stderr: 'Job for vtcs.service canceled.\n' Dec 21 11:54:55 rstream volumio[1191]: } Dec 21 11:54:55 rstream volumio[1191]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 11:54:55 rstream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 11:54:55 rstream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 11:54:55 rstream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:55 rstream systemd[1]: shairport-sync.service: Consumed 1.468s CPU time. Dec 21 11:54:55 rstream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 11:54:55 rstream sudo[1991]: pam_unix(sudo:session): session closed for user root Dec 21 11:54:56 rstream sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 11:53' Dec 21 11:54:56 rstream sudo[2051]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"