Jun 02 03:06:43 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:43.683Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.16:63153 Jun 02 03:06:45 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.0.28 from 192.168.0.16 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:45 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 03:06:45 volumio volumio[1213]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 03:06:45 volumio volumio[1213]: info: Received Get System Info Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 03:06:45 volumio volumio[1213]: info: Discovery: Getting this device information Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:45 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:45 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:45 volumio volumio[1213]: info: Listing playlists Jun 02 03:06:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 02 03:06:46 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 03:06:46 volumio volumio[1213]: info: Received Get System Info Jun 02 03:06:46 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 03:06:46 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 03:06:46 volumio volumio[1213]: info: Discovery: Getting this device information Jun 02 03:06:46 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:46 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:46 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 03:06:47 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 03:06:49 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:49.183Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.16:63153 Jun 02 03:06:49 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:49.184Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.16:63153 Jun 02 03:06:49 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:49.191Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.16:63156 Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 03:06:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 03:06:56 volumio volumio[1213]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 02 03:06:56 volumio volumio[1213]: info: Preparing to save Alsa Options, stopping services first Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioPause Jun 02 03:06:56 volumio volumio[1213]: info: CoreStateMachine::pause Jun 02 03:06:56 volumio volumio[1213]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 03:06:56 volumio volumio[1213]: info: Found match in Cards Database: setting mixer for card Analog Out Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: setDeactiveState invoked Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio vtcs[7404]: [2026-06-02 03:06:56.116] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Jun 02 03:06:56 volumio vtcs[7404]: [2026-06-02 03:06:56.117] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 02 03:06:56 volumio sudo[19858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: Setting mixer Master for card Analog Out Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: setDeactiveState invoked Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 02 03:06:56 volumio systemd[1]: vtcs.service: Deactivated successfully. Jun 02 03:06:56 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 02 03:06:56 volumio systemd[1]: vtcs.service: Consumed 8.516s CPU time. Jun 02 03:06:56 volumio sudo[19864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 02 03:06:56 volumio volumio[1213]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 02 03:06:56 volumio volumio[1213]: info: Disabling external Volume Control Jun 02 03:06:56 volumio sudo[19858]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 03:06:56 volumio volumio[1213]: info: Preparing to generate the ALSA configuration file Jun 02 03:06:56 volumio sudo[19864]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio sudo[19884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 03:06:56 volumio sudo[19884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19873]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio sudo[19875]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 02 03:06:56 volumio volumio[1213]: info: Reading ALSA contributions from plugins. Jun 02 03:06:56 volumio sudo[19884]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 03:06:56 volumio sudo[19892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 03:06:56 volumio sudo[19894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19892]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 03:06:56 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jun 02 03:06:56 volumio qobuz-connect[7367]: 20260602 03:06:56.241 [7367.7367] INFO SampleApp: Stopping Local configuration server Jun 02 03:06:56 volumio sudo[19900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 03:06:56 volumio sudo[19900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 03:06:56 volumio volumio[1213]: info: VolumeController:: Volume=41 Mute =false Jun 02 03:06:56 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:56 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:56 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:56.260Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:56 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:56.260Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:56 volumio volumio[1213]: info: Asound.conf file written Jun 02 03:06:56 volumio sudo[19907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 02 03:06:56 volumio sudo[19907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19907]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: No state is present for card HDMI Jun 02 03:06:56 volumio volumio[1213]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100700" "0x103c" "0x8266" Jun 02 03:06:56 volumio volumio[1213]: Hardware is initialized using a generic method Jun 02 03:06:56 volumio volumio[1213]: No state is present for card HDMI Jun 02 03:06:56 volumio volumio[1213]: No state is present for card Generic Jun 02 03:06:56 volumio volumio[1213]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0221,103c8266,00100103" "0x103c" "0x8266" Jun 02 03:06:56 volumio volumio[1213]: Hardware is initialized using a generic method Jun 02 03:06:56 volumio volumio[1213]: No state is present for card Generic Jun 02 03:06:56 volumio volumio[1213]: info: Output device has changed, restarting MPD Jun 02 03:06:56 volumio volumio[1213]: info: Output device has changed, restarting Shairport Sync Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio sudo[19913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 03:06:56 volumio sudo[19913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19913]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 03:06:56 volumio sudo[19915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 03:06:56 volumio volumio[1213]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: setDeactiveState invoked Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio volumio[1213]: info: Output device has changed, restarting MPD Jun 02 03:06:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 02 03:06:56 volumio sudo[19925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 03:06:56 volumio sudo[19927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 02 03:06:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 02 03:06:56 volumio volumio[1213]: info: Output device has changed, restarting Shairport Sync Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 02 03:06:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 02 03:06:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 02 03:06:56 volumio sudo[19927]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 03:06:56 volumio sudo[19931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 03:06:56 volumio volumio[1213]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: setDeactiveState invoked Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 03:06:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 03:06:56 volumio sudo[19942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 03:06:56 volumio sudo[19949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 02 03:06:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio sudo[19959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 03:06:56 volumio sudo[19959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: MPD Permissions set Jun 02 03:06:56 volumio sudo[19963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 03:06:56 volumio volumio[1213]: info: MPD Permissions set Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio sudo[19963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19946]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19942]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 03:06:56 volumio sudo[19925]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 02 03:06:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 02 03:06:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 02 03:06:56 volumio sudo[19963]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19959]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 03:06:56 volumio sudo[19982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 03:06:56 volumio sudo[19982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 03:06:56 volumio sudo[19984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 03:06:56 volumio volumio[1213]: info: Starting Shairport Sync Jun 02 03:06:56 volumio volumio[1213]: info: Starting Shairport Sync Jun 02 03:06:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 03:06:56 volumio sudo[19992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 03:06:56 volumio sudo[19992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 03:06:56 volumio sudo[19996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 03:06:56 volumio sudo[19996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:56 volumio sudo[19949]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 02 03:06:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 02 03:06:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 02 03:06:56 volumio systemd[1]: shairport-sync.service: Consumed 1min 28.345s CPU time. Jun 02 03:06:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 02 03:06:56 volumio qobuz-connect[7367]: 20260602 03:06:56.549 [7367.7367] INFO SampleApp: shat down connection on UNIX socket Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: setDeactiveState invoked Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio sudo[19996]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jun 02 03:06:56 volumio sudo[19992]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jun 02 03:06:56 volumio volumio[1213]: info: Shairport-Sync Started Jun 02 03:06:56 volumio volumio[1213]: info: Shairport-Sync Started Jun 02 03:06:56 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jun 02 03:06:56 volumio sudo[19982]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19900]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19894]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19998]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 03:06:56 volumio sudo[19998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 03:06:56 volumio sudo[19998]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19984]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: info: Executing endpoint qc_getconfig Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.597 [20002.20002] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.599 [20002.20002] INFO VolumeManager: [0x557d9b356970]: Setting new playback volume: 75 Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 03:06:56 volumio volumio[1213]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.601 [20002.20002] INFO VolumeManager: [0x557d9b356970]: Setting new mute state: 0 Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.601 [20002.20002] INFO AudioStreamManager: [0x557d9b3564d0]: Setting new audio download buffer size: 1048576 Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.601 [20002.20002] INFO QobuzConnect: [0x557d9b357980]: Client initialized! Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.601 [20002.20002] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.607 [20002.20002] INFO LocalConfigManager: [0x557d9b355fb0]: Starting Local Configuration server Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.607 [20002.20002] INFO SampleApp: Starting Local configuration server Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.607 [20002.20002] INFO SampleApp: Connected to UNIX socket client 0x557d9b32cbb0 Jun 02 03:06:56 volumio qobuz-connect[20002]: 20260602 03:06:56.746 [20002.20002] INFO SampleApp: Playback volume changed: 75 Jun 02 03:06:56 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:56 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:56 volumio mpd[20004]: 2026-06-02T03:06:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 03:06:56 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 02 03:06:56 volumio sudo[19915]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio sudo[19931]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:56 volumio volumio[1213]: error: MPD error: The expression evaluated to a falsy value: Jun 02 03:06:56 volumio volumio[1213]: assert.ok(self.idling) Jun 02 03:06:56 volumio volumio[1213]: error: The expression evaluated to a falsy value: Jun 02 03:06:56 volumio volumio[1213]: assert.ok(self.idling) Jun 02 03:06:56 volumio volumio[1213]: error: updateQueue error: null Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio sudo[20045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 03:06:59 volumio sudo[20045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:59 volumio sudo[20047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 03:06:59 volumio sudo[20047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:59 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 02 03:06:59 volumio sudo[20045]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:59 volumio sudo[20047]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:59 volumio volumio[1213]: info: Executing endpoint tc_getconfig Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 02 03:06:59 volumio vtcs[20051]: STARTING TidalConnect services, version: 1.6.1 Jun 02 03:06:59 volumio vtcs[20051]: STARTED TidalConnect services. Jun 02 03:06:59 volumio volumio[1213]: info: Executing endpoint tc_connect Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 02 03:06:59 volumio volumio[1213]: info: Connecting to TidalConnect Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: 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} Jun 02 03:06:59 volumio volumio[1213]: verbose: CURRENT POSITION 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::syncState stateService stop Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus stop Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: No code Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: 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} Jun 02 03:06:59 volumio volumio[1213]: verbose: CURRENT POSITION 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::syncState stateService stop Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus stop Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: No code Jun 02 03:06:59 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:06:59 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:06:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:06:59 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.380Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.380Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.380Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:59 volumio volumio5-onboarding[1912]: time=2026-06-02T00:06:59.381Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio sudo[20077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 03:06:59 volumio sudo[20077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:59 volumio sudo[20077]: pam_unix(sudo:session): session closed for user root Jun 02 03:06:59 volumio volumio[1213]: info: TidalConnect service stoped! Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio volumio[1213]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 03:06:59 volumio sudo[20090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 03:06:59 volumio sudo[20090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 03:06:59 volumio sudo[20090]: pam_unix(sudo:session): session closed for user root Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 02 03:07:00 volumio volumio[1213]: info: CoreStateMachine::getcurrentVolume Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 03:07:00 volumio volumio[1213]: info: VolumeController:: Volume=41 Mute =false Jun 02 03:07:00 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:07:00 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:07:00 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:07:00 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:07:00 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:07:00 volumio volumio[1213]: info: CoreStateMachine::updateTrackBlock Jun 02 03:07:00 volumio volumio[1213]: info: CorePlayQueue::getTrackBlock Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 03:07:00 volumio volumio5-onboarding[1912]: time=2026-06-02T00:07:00.201Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:07:00 volumio volumio5-onboarding[1912]: time=2026-06-02T00:07:00.203Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:07:00 volumio volumio[1213]: info: VolumeController:: Volume=41 Mute =false Jun 02 03:07:00 volumio volumio[1213]: info: CoreStateMachine::pushState Jun 02 03:07:00 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Jun 02 03:07:00 volumio volumio[1213]: info: MRS: Pushing multiroomSync output update for this device Jun 02 03:07:00 volumio volumio[1213]: info: MRS: Pushing multiroomSync output Jun 02 03:07:00 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Jun 02 03:07:00 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Jun 02 03:07:00 volumio volumio5-onboarding[1912]: time=2026-06-02T00:07:00.218Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" state=STATUS_STOPPED positionMs=0 volume=41 Jun 02 03:07:00 volumio volumio5-onboarding[1912]: time=2026-06-02T00:07:00.218Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.16:63156 @ 0xc0000ae180" id= title= Jun 02 03:07:02 volumio volumio[1213]: info: TidalConnect service started! Jun 02 03:07:02 volumio volumio[1213]: info: TidalConnect service started! Jun 02 03:07:02 volumio volumio[1213]: info: TidalConnect service started! Jun 02 03:07:02 volumio volumio[1213]: info: TidalConnect service started! Jun 02 03:07:05 volumio volumio[1213]: info: CoreCommandRouter::volumioGetQueue Jun 02 03:07:05 volumio volumio[1213]: info: CoreStateMachine::getQueue Jun 02 03:07:05 volumio volumio[1213]: info: CorePlayQueue::getQueue Jun 02 03:07:14 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 02 03:07:14 volumio volumio[1213]: info: Preload queue cleared Jun 02 03:07:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 02 03:07:15 volumio volumio[1213]: info: Preload queue cleared Jun 02 03:07:19 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 02 03:07:19 volumio volumio[1213]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 03:07:19 volumio volumio[1213]: XMLStructuredError: Premature end of data in tag br line 187 (Line: 195, Column: 7) Jun 02 03:07:19 volumio volumio[1213]: at new XMLStructuredError (/volumio/node_modules/libxmljs/dist/lib/types.js:23:28) Jun 02 03:07:19 volumio volumio[1213]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:190:23 Jun 02 03:07:19 volumio volumio[1213]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 02 03:07:19 volumio volumio[1213]: at /volumio/app/plugins/music_service/webradio/index.js:434:31 Jun 02 03:07:19 volumio volumio[1213]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Jun 02 03:07:19 volumio volumio[1213]: domain: 1, Jun 02 03:07:19 volumio volumio[1213]: code: 77, Jun 02 03:07:19 volumio volumio[1213]: level: 3, Jun 02 03:07:19 volumio volumio[1213]: column: 7, Jun 02 03:07:19 volumio volumio[1213]: file: '', Jun 02 03:07:19 volumio volumio[1213]: line: 195, Jun 02 03:07:19 volumio volumio[1213]: str1: 'br', Jun 02 03:07:19 volumio volumio[1213]: str2: undefined, Jun 02 03:07:19 volumio volumio[1213]: str3: undefined, Jun 02 03:07:19 volumio volumio[1213]: int1: 187 Jun 02 03:07:19 volumio volumio[1213]: } Jun 02 03:07:19 volumio volumio[1213]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 03:07:19 volumio sudo[20162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 03:06' Jun 02 03:07:19 volumio sudo[20162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"