May 25 18:54:00 volumio volumiobt[2432]: 2026-05-25 16:54:00 a2dp-agent [INFO] Agent registered at /local/a2dpagent May 25 18:54:00 volumio volumiobt[2432]: 2026-05-25 16:54:00 a2dp-agent [INFO] Agent set as default May 25 18:54:00 volumio volumiobt[2432]: 2026-05-25 16:54:00 a2dp-agent [INFO] A2DP agent running, waiting for connections... May 25 18:54:00 volumio volumio[1251]: error: updateQueue error: null May 25 18:54:00 volumio volumio[1251]: info: Executing endpoint qc_getconfig May 25 18:54:00 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.649 [2391.2391] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.868 [2391.2391] INFO VolumeManager: [0x17ec9b8]: Setting new playback volume: 75 May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.868 [2391.2391] INFO VolumeManager: [0x17ec9b8]: Setting new mute state: 0 May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.868 [2391.2391] INFO AudioStreamManager: [0x17ec710]: Setting new audio download buffer size: 1048576 May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.868 [2391.2391] INFO QobuzConnect: [0x17ed280]: Client initialized! May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.868 [2391.2391] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.898 [2391.2391] INFO LocalConfigManager: [0x17ec438]: Starting Local Configuration server May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.898 [2391.2391] INFO SampleApp: Starting Local configuration server May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.899 [2391.2391] INFO SampleApp: Connected to UNIX socket client 0x17d7818 May 25 18:54:00 volumio qobuz-connect[2391]: 20260525 16:54:00.899 [2391.2391] INFO SampleApp: Playback volume changed: 75 May 25 18:54:00 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 18:54:00 volumio volumio[1251]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 18:54:01 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:54:01 volumio volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 May 25 18:54:01 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:01 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:01 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:54:01 volumio volumio[1251]: info: Preparing to generate the ALSA configuration file May 25 18:54:01 volumio sudo[2450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:54:01 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:01 volumio volumio[1251]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:54:01 volumio volumio[1251]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:54:01 volumio volumio[1251]: info: Asound.conf file unchanged, so no further update is needed May 25 18:54:01 volumio volumio[1251]: info: Output device has changed, restarting MPD May 25 18:54:01 volumio volumio[1251]: info: Output device has changed, restarting Shairport Sync May 25 18:54:01 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:01 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 18:54:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 18:54:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:54:01 volumio systemd[1]: shairport-sync.service: Consumed 1.834s CPU time. May 25 18:54:01 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 18:54:01 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:01 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root May 25 18:54:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:54:01 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root May 25 18:54:01 volumio volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 18:54:01 volumio volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 18:54:01 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:02 volumio sudo[2464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 18:54:02 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:54:02 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:02 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:02 volumio volumio[1251]: info: Preparing to generate the ALSA configuration file May 25 18:54:02 volumio sudo[2464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:02 volumio sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:02 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:02 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 18:54:02 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 18:54:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 18:54:02 volumio systemd[1]: mpd.service: Consumed 3.292s CPU time. May 25 18:54:02 volumio sudo[2493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 18:54:02 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:02 volumio sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:02 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:02 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 18:54:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 18:54:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 18:54:02 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:02 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 18:54:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 18:54:02 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root May 25 18:54:02 volumio volumio[1251]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 18:54:02 volumio volumio[1251]: info: Reading ALSA contributions from plugins. May 25 18:54:02 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root May 25 18:54:02 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root May 25 18:54:02 volumio sudo[2497]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 18:54:02 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 18:54:02 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root May 25 18:54:03 volumio volumio[1251]: info: MPD Permissions set May 25 18:54:03 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:54:03 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 18:54:03 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:03 volumio sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 18:54:03 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:03 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 25 18:54:03 volumio qobuz-connect[2391]: 20260525 16:54:03.421 [2391.2391] INFO SampleApp: Stopping Local configuration server May 25 18:54:03 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 25 18:54:03 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root May 25 18:54:03 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.242 from 192.168.1.241 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:04 volumio volumio[1251]: info: BOOT COMPLETED May 25 18:54:04 volumio volumio[1251]: info: Executing endpoint tc_getconfig May 25 18:54:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 25 18:54:04 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:54:04 volumio vtcs[2508]: STARTING TidalConnect services, version: 1.6.1 May 25 18:54:04 volumio vtcs[2508]: STARTED TidalConnect services. May 25 18:54:04 volumio volumio[1251]: info: CoreCommandRouter::volumioGetVisibleSources May 25 18:54:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 18:54:04 volumio qobuz-connect[2391]: 20260525 16:54:04.918 [2391.2391] INFO SampleApp: shat down connection on UNIX socket May 25 18:54:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 25 18:54:04 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 25 18:54:04 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:54:04 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:54:05 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 25 18:54:05 volumio sudo[2522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:54:05 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 18:54:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 18:54:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:54:05 volumio systemd[1]: shairport-sync.service: Consumed 1.210s CPU time. May 25 18:54:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:54:05 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 25 18:54:05 volumio volumio[1251]: info: Received Get System Info May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:54:05 volumio volumio[1251]: info: Discovery: Getting this device information May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:54:05 volumio volumio-remote-updater[743]: Test mode disabled May 25 18:54:05 volumio volumio-remote-updater[743]: Alpha mode disabled May 25 18:54:05 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 18:54:05 volumio volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 18:54:05 volumio volumio[1251]: info: Received Get System Version May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 18:54:05 volumio volumio[1251]: info: Received Get System Info May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:54:05 volumio volumio[1251]: info: Discovery: Getting this device information May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::volumioGetVisibleSources May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 18:54:05 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:05 volumio volumio[1251]: info: Retrieving Cloud Streaming UI May 25 18:54:06 volumio volumio[1251]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 25 18:54:06 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 25 18:54:06 volumio mpd[2502]: 2026-05-25T16:54:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 18:54:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 25 18:54:06 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:54:06 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:54:06 volumio sudo[2464]: pam_unix(sudo:session): session closed for user root May 25 18:54:07 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:54:07 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 25 18:54:07 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:07 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:07 volumio volumio[1251]: info: Executing endpoint tc_connect May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 25 18:54:07 volumio volumio[1251]: info: Connecting to TidalConnect May 25 18:54:07 volumio volumio[1251]: info: TidalConnect service started! May 25 18:54:07 volumio volumio[1251]: info: Executing endpoint qc_getconfig May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.544 [2523.2523] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.551 [2523.2523] INFO VolumeManager: [0xa869b8]: Setting new playback volume: 75 May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.551 [2523.2523] INFO VolumeManager: [0xa869b8]: Setting new mute state: 0 May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.551 [2523.2523] INFO AudioStreamManager: [0xa86710]: Setting new audio download buffer size: 1048576 May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.551 [2523.2523] INFO QobuzConnect: [0xa87280]: Client initialized! May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.551 [2523.2523] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.583 [2523.2523] INFO LocalConfigManager: [0xa86438]: Starting Local Configuration server May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.583 [2523.2523] INFO SampleApp: Starting Local configuration server May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.583 [2523.2523] INFO SampleApp: Connected to UNIX socket client 0xa71818 May 25 18:54:07 volumio qobuz-connect[2523]: 20260525 16:54:07.696 [2523.2523] INFO SampleApp: Playback volume changed: 75 May 25 18:54:07 volumio volumio[1251]: info: Getting Tidal Cloud Configuration May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:07 volumio volumio[1251]: info: Getting Qobuz Cloud Configuration May 25 18:54:07 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:07 volumio volumio[1251]: info: Getting Spotify Cloud Configuration May 25 18:54:07 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:07 volumio volumio[1251]: info: Saving Spotify Acccount May 25 18:54:07 volumio volumio[1251]: info: Got it May 25 18:54:07 volumio volumio[1251]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 25 18:54:07 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:07 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:07 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: 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} May 25 18:54:08 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: No code May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: 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} May 25 18:54:08 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: No code May 25 18:54:08 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:08 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 18:54:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 25 18:54:09 volumio volumio[1251]: info: Loading i18n strings for locale es May 25 18:54:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 25 18:54:09 volumio volumio[1251]: info: Setting timezone to Europe/Madrid May 25 18:54:09 volumio sudo[2584]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 25 18:54:09 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:09 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root May 25 18:54:09 volumio sudo[2588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Madrid /etc/localtime May 25 18:54:09 volumio sudo[2588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:10 volumio sudo[2588]: pam_unix(sudo:session): session closed for user root May 25 18:54:10 volumio sudo[2592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 25 18:54:10 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:10 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root May 25 18:54:10 volumio sudo[2596]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Madrid May 25 18:54:10 volumio sudo[2596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:10 volumio dbus-daemon[722]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.39' (uid=0 pid=2597 comm="/usr/bin/timedatectl set-timezone Europe/Madrid") May 25 18:54:10 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 25 18:54:11 volumio dbus-daemon[722]: [system] Successfully activated service 'org.freedesktop.timedate1' May 25 18:54:11 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 25 18:54:11 volumio sudo[2596]: pam_unix(sudo:session): session closed for user root May 25 18:54:11 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 18:54:11 volumio volumio[1251]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 18:54:11 volumio volumio[1251]: info: Fetching Streaming Services browse cache May 25 18:54:11 volumio volumio[1251]: info: Asound.conf file written May 25 18:54:11 volumio sudo[2601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 18:54:12 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:12 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root May 25 18:54:12 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 18:54:12 volumio volumio[1251]: No state is present for card b1 May 25 18:54:12 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 18:54:12 volumio volumio[1251]: /usr/share/alsa/init/bcm2835:14: control element not found May 25 18:54:12 volumio volumio[1251]: No state is present for card b1 May 25 18:54:12 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 18:54:12 volumio volumio[1251]: No state is present for card Headphones May 25 18:54:12 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 18:54:12 volumio volumio[1251]: /usr/share/alsa/init/bcm2835:20: control element not found May 25 18:54:12 volumio volumio[1251]: No state is present for card Headphones May 25 18:54:12 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 18:54:12 volumio volumio[1251]: No state is present for card vc4hdmi May 25 18:54:12 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 18:54:12 volumio volumio[1251]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 18:54:12 volumio volumio[1251]: Hardware is initialized using a generic method May 25 18:54:12 volumio volumio[1251]: No state is present for card vc4hdmi May 25 18:54:12 volumio volumio[1251]: info: Output device has changed, restarting MPD May 25 18:54:12 volumio volumio[1251]: info: Output device has changed, restarting Shairport Sync May 25 18:54:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:12 volumio sudo[2607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 18:54:12 volumio sudo[2607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:12 volumio sudo[2607]: pam_unix(sudo:session): session closed for user root May 25 18:54:12 volumio sudo[2610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 18:54:12 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:12 volumio volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 18:54:12 volumio volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 18:54:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 18:54:12 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 18:54:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 18:54:12 volumio systemd[1]: mpd.service: Consumed 3.621s CPU time. May 25 18:54:12 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 18:54:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 18:54:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 18:54:12 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:54:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:12 volumio vtcs[2508]: [2026-05-25 18:54:12.960] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 25 18:54:12 volumio vtcs[2508]: [2026-05-25 18:54:12.963] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer May 25 18:54:12 volumio vtcs[2508]: [2026-05-25 18:54:12.963] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 25 18:54:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 18:54:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 18:54:13 volumio sudo[2620]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 18:54:13 volumio sudo[2620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 18:54:13 volumio sudo[2620]: pam_unix(sudo:session): session closed for user root May 25 18:54:13 volumio sudo[2623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:13 volumio sudo[2623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:13 volumio volumio[1251]: error: updateQueue error: null May 25 18:54:13 volumio sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:13 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:13 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 25 18:54:13 volumio systemd[1]: vtcs.service: Killing process 2521 (vtcs) with signal SIGKILL. May 25 18:54:13 volumio systemd[1]: vtcs.service: Deactivated successfully. May 25 18:54:13 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 25 18:54:13 volumio sudo[2633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 18:54:13 volumio sudo[2633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:13 volumio sudo[2623]: pam_unix(sudo:session): session closed for user root May 25 18:54:13 volumio sudo[2622]: pam_unix(sudo:session): session closed for user root May 25 18:54:13 volumio sudo[2633]: pam_unix(sudo:session): session closed for user root May 25 18:54:13 volumio sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 18:54:13 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:14 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 25 18:54:14 volumio qobuz-connect[2523]: 20260525 16:54:14.188 [2523.2523] INFO SampleApp: Stopping Local configuration server May 25 18:54:14 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:14 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:14 volumio volumio[1251]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:54:14 volumio volumio[1251]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:54:14 volumio volumio[1251]: info: MPD Permissions set May 25 18:54:15 volumio sudo[2641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 18:54:15 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:15 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 25 18:54:15 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root May 25 18:54:15 volumio volumio[1251]: Updating browse sources language May 25 18:54:15 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 18:54:15 volumio qobuz-connect[2523]: 20260525 16:54:15.591 [2523.2523] INFO SampleApp: shat down connection on UNIX socket May 25 18:54:15 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 25 18:54:15 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:54:15 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:54:15 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root May 25 18:54:16 volumio mpd[2629]: 2026-05-25T18:54:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 18:54:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 25 18:54:16 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:17 volumio volumio[1251]: info: Got Tidal Cloud Configuration May 25 18:54:17 volumio volumio[1251]: info: Got it May 25 18:54:17 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:17 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:54:17 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:54:18 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 25 18:54:18 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:54:18 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:18 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:18 volumio volumio[1251]: info: Executing endpoint qc_getconfig May 25 18:54:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.309 [2647.2647] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.326 [2647.2647] INFO VolumeManager: [0x723058]: Setting new playback volume: 75 May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.326 [2647.2647] INFO VolumeManager: [0x723058]: Setting new mute state: 0 May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.326 [2647.2647] INFO AudioStreamManager: [0x722db0]: Setting new audio download buffer size: 1048576 May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.326 [2647.2647] INFO QobuzConnect: [0x723920]: Client initialized! May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.326 [2647.2647] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 25 18:54:18 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 18:54:18 volumio volumio[1251]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.368 [2647.2647] INFO LocalConfigManager: [0x722ad8]: Starting Local Configuration server May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.368 [2647.2647] INFO SampleApp: Starting Local configuration server May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.369 [2647.2647] INFO SampleApp: Connected to UNIX socket client 0x70d818 May 25 18:54:18 volumio volumio[1251]: info: Executing endpoint tc_getconfig May 25 18:54:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 25 18:54:18 volumio vtcs[2645]: STARTING TidalConnect services, version: 1.6.1 May 25 18:54:18 volumio vtcs[2645]: STARTED TidalConnect services. May 25 18:54:18 volumio qobuz-connect[2647]: 20260525 18:54:18.459 [2647.2647] INFO SampleApp: Playback volume changed: 75 May 25 18:54:18 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:54:18 volumio volumio[1251]: error: updateQueue error: null May 25 18:54:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 25 18:54:18 volumio volumio[1251]: info: Loading i18n strings for locale es May 25 18:54:18 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 25 18:54:18 volumio volumio[1251]: info: Setting timezone to Europe/Madrid May 25 18:54:18 volumio sudo[2676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:54:18 volumio sudo[2676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 18:54:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 18:54:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:54:18 volumio systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. May 25 18:54:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:54:19 volumio sudo[2680]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 25 18:54:19 volumio sudo[2680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:19 volumio sudo[2676]: pam_unix(sudo:session): session closed for user root May 25 18:54:19 volumio sudo[2680]: pam_unix(sudo:session): session closed for user root May 25 18:54:19 volumio sudo[2713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Madrid /etc/localtime May 25 18:54:19 volumio sudo[2713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:19 volumio sudo[2713]: pam_unix(sudo:session): session closed for user root May 25 18:54:19 volumio sudo[2717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 25 18:54:19 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:19 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root May 25 18:54:20 volumio sudo[2721]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Madrid May 25 18:54:20 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:20 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root May 25 18:54:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 25 18:54:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 25 18:54:20 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:20 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:20 volumio volumio[1251]: info: Fetching Streaming Services browse cache May 25 18:54:20 volumio sudo[2724]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 18:54:20 volumio sudo[2724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:20 volumio volumio[1251]: info: Executing endpoint tc_connect May 25 18:54:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 25 18:54:20 volumio volumio[1251]: info: Connecting to TidalConnect May 25 18:54:20 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:54:20 volumio volumio[1251]: info: TidalConnect service started! May 25 18:54:20 volumio volumio[1251]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:54:20 volumio volumio[1251]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: 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} May 25 18:54:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: No code May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: 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} May 25 18:54:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: No code May 25 18:54:21 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:21 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:21 volumio sudo[2724]: pam_unix(sudo:session): session closed for user root May 25 18:54:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:54:22 volumio volumio[1251]: Updating browse sources language May 25 18:54:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 18:54:22 volumio sudo[2736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 18:54:22 volumio sudo[2736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:22 volumio sudo[2736]: pam_unix(sudo:session): session closed for user root May 25 18:54:22 volumio volumio[1251]: info: Received Get System Info May 25 18:54:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:54:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:54:22 volumio volumio[1251]: info: Discovery: Getting this device information May 25 18:54:22 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:54:23 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 18:54:23 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 18:54:24 volumio volumio5-onboarding[1879]: time=2026-05-25T16:54:24.465Z level=INFO msg="service successfully established" component=discovery/localnet May 25 18:54:25 volumio volumio[1251]: info: TidalConnect service started! May 25 18:54:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 25 18:54:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 25 18:54:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 25 18:54:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 18:54:41 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 25 18:54:41 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 18:54:41 volumio volumio-remote-updater[743]: Test mode disabled May 25 18:54:41 volumio volumio-remote-updater[743]: Alpha mode disabled May 25 18:54:41 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled May 25 18:54:41 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 25 18:54:41 volumio volumio[1251]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 25 18:54:41 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 25 18:54:50 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 25 18:54:50 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 25 18:54:50 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 18:54:50 volumio volumio-remote-updater[743]: Test mode disabled May 25 18:54:50 volumio volumio-remote-updater[743]: Alpha mode disabled May 25 18:54:50 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled May 25 18:54:50 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 25 18:54:50 volumio volumio[1251]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 25 18:54:50 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 25 18:54:51 volumio volumio[1251]: info: Preparing to save Alsa Options, stopping services first May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:51 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::volumioPause May 25 18:54:51 volumio volumio[1251]: info: CoreStateMachine::pause May 25 18:54:51 volumio volumio[1251]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-amp2","label":"HiFiBerry Amp2"},"output_device":{"value":1,"label":"HiFiBerry Amp2"},"disallowPush":true} May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:51 volumio volumio[1251]: info: Enabling I2S DAC: HiFiBerry Amp2 May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:51 volumio sudo[2793]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 25 18:54:51 volumio sudo[2793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:51 volumio sudo[2793]: pam_unix(sudo:session): session closed for user root May 25 18:54:51 volumio volumio[1251]: info: No Overlays Loaded May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 25 18:54:51 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:51 volumio sudo[2796]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus May 25 18:54:51 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:51 volumio volumio[1251]: warn: Unable to locate the device HiFiBerry Amp2 a reboot may be required. May 25 18:54:52 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 25 18:54:52 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status May 25 18:54:52 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 25 18:54:52 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 25 18:54:52 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 25 18:54:52 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:52 volumio volumio[1251]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry Amp2 May 25 18:54:52 volumio (udev-worker)[2806]: controlC3: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 3' failed with exit code 99. May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 25 18:54:52 volumio volumio[1251]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Amp2 Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 25 18:54:52 volumio volumio[1251]: info: Disabling external Volume Control May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 25 18:54:52 volumio volumio[1251]: info: Preparing to generate the ALSA configuration file May 25 18:54:52 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:52 volumio volumio[1251]: info: Retrieving Cloud Streaming UI May 25 18:54:52 volumio volumio[1251]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 18:54:52 volumio volumio[1251]: info: Reading ALSA contributions from plugins. May 25 18:54:52 volumio volumio[1251]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 May 25 18:54:53 volumio volumio[1251]: info: I2S Param [object Object] successfully enabled May 25 18:54:53 volumio volumio[1251]: info: Getting Tidal Cloud Configuration May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:53 volumio volumio[1251]: info: Getting Qobuz Cloud Configuration May 25 18:54:53 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:53 volumio volumio[1251]: info: Getting Spotify Cloud Configuration May 25 18:54:53 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:53 volumio volumio[1251]: info: Saving Spotify Acccount May 25 18:54:53 volumio volumio[1251]: info: Got it May 25 18:54:53 volumio volumio[1251]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 18:54:53 volumio volumio[1251]: info: Getting Alsa Cards List without I2S DAC May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 25 18:54:53 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:54 volumio volumio[1251]: info: Retrieving Cloud Streaming UI May 25 18:54:54 volumio volumio[1251]: info: VolumeController:: Volume=undefined Mute =false May 25 18:54:54 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:54 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:54 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:54 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:54 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:54 volumio volumio[1251]: info: Getting Tidal Cloud Configuration May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:54 volumio volumio[1251]: info: Getting Qobuz Cloud Configuration May 25 18:54:54 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:54 volumio volumio[1251]: info: Getting Spotify Cloud Configuration May 25 18:54:54 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:54 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:54 volumio volumio[1251]: info: Saving Spotify Acccount May 25 18:54:55 volumio volumio[1251]: info: Got it May 25 18:54:55 volumio volumio[1251]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 25 18:54:55 volumio volumio[1251]: info: Got Tidal Cloud Configuration May 25 18:54:55 volumio volumio[1251]: info: Got it May 25 18:54:55 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:55 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:55 volumio volumio[1251]: info: Retrieving Cloud Streaming UI May 25 18:54:55 volumio volumio[1251]: info: Asound.conf file written May 25 18:54:55 volumio sudo[2849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 18:54:55 volumio sudo[2849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:55 volumio sudo[2849]: pam_unix(sudo:session): session closed for user root May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: No state is present for card b1 May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: /usr/share/alsa/init/bcm2835:14: control element not found May 25 18:54:55 volumio volumio[1251]: No state is present for card b1 May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: No state is present for card Headphones May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: /usr/share/alsa/init/bcm2835:20: control element not found May 25 18:54:55 volumio volumio[1251]: No state is present for card Headphones May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: No state is present for card vc4hdmi May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 18:54:55 volumio volumio[1251]: Hardware is initialized using a generic method May 25 18:54:55 volumio volumio[1251]: No state is present for card vc4hdmi May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: No state is present for card sndrpihifiberry May 25 18:54:55 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 25 18:54:55 volumio volumio[1251]: Found hardware: "HifiberryDacp" "" "" "" "" May 25 18:54:55 volumio volumio[1251]: Hardware is initialized using a generic method May 25 18:54:55 volumio volumio[1251]: No state is present for card sndrpihifiberry May 25 18:54:55 volumio volumio[1251]: info: Output device has changed, restarting MPD May 25 18:54:55 volumio volumio[1251]: info: Output device has changed, restarting Shairport Sync May 25 18:54:55 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:55 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:55 volumio sudo[2858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 18:54:55 volumio sudo[2858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:55 volumio sudo[2858]: pam_unix(sudo:session): session closed for user root May 25 18:54:55 volumio sudo[2861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 18:54:55 volumio volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 18:54:55 volumio volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 18:54:55 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:56 volumio sudo[2861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:56 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:54:56 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:56 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:56 volumio vtcs[2645]: [2026-05-25 18:54:56.100] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 25 18:54:56 volumio volumio[1251]: info: Output device has changed, restarting MPD May 25 18:54:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 18:54:56 volumio volumio[1251]: info: Output device has changed, restarting Shairport Sync May 25 18:54:56 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:56 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:56 volumio sudo[2871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:56 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 18:54:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 18:54:56 volumio systemd[1]: mpd.service: Consumed 3.519s CPU time. May 25 18:54:56 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 18:54:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 18:54:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 18:54:56 volumio sudo[2871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 18:54:56 volumio volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 18:54:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 18:54:56 volumio volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 18:54:56 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:56 volumio sudo[2873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 18:54:56 volumio sudo[2873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:56 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:54:56 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:56 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:56 volumio sudo[2874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 18:54:56 volumio sudo[2874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:56 volumio sudo[2873]: pam_unix(sudo:session): session closed for user root May 25 18:54:56 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 25 18:54:56 volumio sudo[2881]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 18:54:56 volumio systemd[1]: vtcs.service: Killing process 2668 (vtcs) with signal SIGKILL. May 25 18:54:56 volumio sudo[2881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 18:54:56 volumio systemd[1]: vtcs.service: Deactivated successfully. May 25 18:54:56 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 25 18:54:56 volumio sudo[2881]: pam_unix(sudo:session): session closed for user root May 25 18:54:56 volumio sudo[2871]: pam_unix(sudo:session): session closed for user root May 25 18:54:56 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 18:54:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 18:54:57 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 18:54:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 18:54:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 18:54:57 volumio sudo[2893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:57 volumio sudo[2893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 18:54:57 volumio sudo[2887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:57 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:57 volumio sudo[2891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:54:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 18:54:57 volumio sudo[2891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:57 volumio sudo[2902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 18:54:57 volumio sudo[2903]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 18:54:57 volumio sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 18:54:57 volumio sudo[2893]: pam_unix(sudo:session): session closed for user root May 25 18:54:57 volumio sudo[2903]: pam_unix(sudo:session): session closed for user root May 25 18:54:57 volumio sudo[2902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:57 volumio volumio[1251]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 25 18:54:57 volumio volumio[1251]: info: CoreStateMachine::getcurrentVolume May 25 18:54:57 volumio volumio[1251]: info: CoreCommandRouter::volumioRetrievevolume May 25 18:54:57 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root May 25 18:54:57 volumio sudo[2902]: pam_unix(sudo:session): session closed for user root May 25 18:54:57 volumio sudo[2891]: pam_unix(sudo:session): session closed for user root May 25 18:54:57 volumio sudo[2915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 18:54:57 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:58 volumio sudo[2917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 18:54:58 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root May 25 18:54:58 volumio sudo[2917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:58 volumio volumio[1251]: info: Retrieving Cloud Streaming UI May 25 18:54:58 volumio volumio[1251]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 25 18:54:58 volumio volumio[1251]: info: MPD Permissions set May 25 18:54:58 volumio volumio[1251]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 25 18:54:58 volumio volumio[1251]: info: MPD Permissions set May 25 18:54:58 volumio volumio[1251]: info: Got Tidal Cloud Configuration May 25 18:54:58 volumio volumio[1251]: info: Got it May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:58 volumio volumio[1251]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 May 25 18:54:58 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 25 18:54:58 volumio qobuz-connect[2647]: 20260525 18:54:58.411 [2647.2647] INFO SampleApp: Stopping Local configuration server May 25 18:54:58 volumio sudo[2921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 18:54:58 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:54:58 volumio volumio[1251]: info: Getting Tidal Cloud Configuration May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:58 volumio volumio[1251]: info: Getting Qobuz Cloud Configuration May 25 18:54:58 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:58 volumio volumio[1251]: info: Getting Spotify Cloud Configuration May 25 18:54:58 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:54:58 volumio volumio[1251]: info: Saving Spotify Acccount May 25 18:54:58 volumio volumio[1251]: info: Got it May 25 18:54:58 volumio volumio[1251]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 25 18:54:58 volumio volumio[1251]: info: VolumeController:: Volume=undefined Mute =false May 25 18:54:58 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:54:58 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:54:58 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:54:58 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:54:58 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:54:58 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:54:59 volumio volumio[1251]: info: CoreStateMachine::updateTrackBlock May 25 18:54:59 volumio volumio[1251]: info: CorePlayQueue::getTrackBlock May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::volumioRetrievevolume May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:54:59 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:00 volumio volumio[1251]: info: Getting Tidal Cloud Configuration May 25 18:55:00 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:55:00 volumio volumio[1251]: info: Getting Qobuz Cloud Configuration May 25 18:55:00 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:55:00 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:55:00 volumio qobuz-connect[2647]: 20260525 18:55:00.397 [2647.2647] INFO SampleApp: shat down connection on UNIX socket May 25 18:55:00 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 25 18:55:00 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:55:00 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:55:00 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root May 25 18:55:00 volumio sudo[2917]: pam_unix(sudo:session): session closed for user root May 25 18:55:00 volumio volumio[1251]: info: Getting Spotify Cloud Configuration May 25 18:55:00 volumio volumio[1251]: info: Asking plugin for UI Config May 25 18:55:00 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:55:00 volumio volumio[1251]: info: Saving Spotify Acccount May 25 18:55:00 volumio volumio[1251]: info: Got it May 25 18:55:00 volumio volumio[1251]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 25 18:55:00 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:00 volumio volumio[1251]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 May 25 18:55:01 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:01 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 25 18:55:01 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:55:01 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:01 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:01 volumio mpd[2912]: 2026-05-25T18:55:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 18:55:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 25 18:55:01 volumio sudo[2861]: pam_unix(sudo:session): session closed for user root May 25 18:55:01 volumio sudo[2874]: pam_unix(sudo:session): session closed for user root May 25 18:55:01 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:55:01 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:55:01 volumio volumio[1251]: info: Got Tidal Cloud Configuration May 25 18:55:01 volumio volumio[1251]: info: Preparing to generate the ALSA configuration file May 25 18:55:01 volumio sudo[2960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:55:01 volumio sudo[2960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:01 volumio volumio[1251]: info: Executing endpoint qc_getconfig May 25 18:55:01 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 18:55:01 volumio sudo[2962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:55:01 volumio sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.659 [2950.2950] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.666 [2950.2950] INFO VolumeManager: [0x174c058]: Setting new playback volume: 75 May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.667 [2950.2950] INFO VolumeManager: [0x174c058]: Setting new mute state: 0 May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.667 [2950.2950] INFO AudioStreamManager: [0x174bdb0]: Setting new audio download buffer size: 1048576 May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.667 [2950.2950] INFO QobuzConnect: [0x174c920]: Client initialized! May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.667 [2950.2950] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 25 18:55:01 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:01 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 18:55:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 18:55:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:55:01 volumio systemd[1]: shairport-sync.service: Consumed 1.667s CPU time. May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.723 [2950.2950] INFO LocalConfigManager: [0x174bad8]: Starting Local Configuration server May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.723 [2950.2950] INFO SampleApp: Starting Local configuration server May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.724 [2950.2950] INFO SampleApp: Connected to UNIX socket client 0x1736818 May 25 18:55:01 volumio volumio[1251]: info: VolumeController:: Volume=undefined Mute =false May 25 18:55:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:55:01 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:01 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:01 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:01 volumio qobuz-connect[2950]: 20260525 18:55:01.817 [2950.2950] INFO SampleApp: Playback volume changed: 75 May 25 18:55:01 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:01 volumio sudo[2960]: pam_unix(sudo:session): session closed for user root May 25 18:55:01 volumio sudo[2962]: pam_unix(sudo:session): session closed for user root May 25 18:55:01 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:01 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:01 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:02 volumio volumio[1251]: info: Got it May 25 18:55:02 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:55:02 volumio volumio[1251]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 18:55:02 volumio volumio[1251]: info: Reading ALSA contributions from plugins. May 25 18:55:02 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 18:55:02 volumio volumio[1251]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 18:55:02 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:55:02 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:55:03 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:03 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:03 volumio volumio[1251]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:03 volumio volumio[1251]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:03 volumio volumio[1251]: info: Got Tidal Cloud Configuration May 25 18:55:03 volumio volumio[1251]: info: Got it May 25 18:55:03 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:55:03 volumio volumio[1251]: error: MPD error: The expression evaluated to a falsy value: May 25 18:55:03 volumio volumio[1251]: assert.ok(self.idling) May 25 18:55:03 volumio volumio[1251]: error: The expression evaluated to a falsy value: May 25 18:55:03 volumio volumio[1251]: assert.ok(self.idling) May 25 18:55:03 volumio volumio[1251]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:03 volumio volumio[1251]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:03 volumio volumio[1251]: error: updateQueue error: null May 25 18:55:03 volumio sudo[2999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 18:55:03 volumio volumio[1251]: info: Asound.conf file written May 25 18:55:03 volumio sudo[2999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:03 volumio sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 18:55:03 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:03 volumio sudo[3005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 18:55:03 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 25 18:55:03 volumio sudo[3005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:03 volumio sudo[2999]: pam_unix(sudo:session): session closed for user root May 25 18:55:03 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root May 25 18:55:03 volumio sudo[3005]: pam_unix(sudo:session): session closed for user root May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: No state is present for card b1 May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: /usr/share/alsa/init/bcm2835:14: control element not found May 25 18:55:04 volumio volumio[1251]: No state is present for card b1 May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: No state is present for card Headphones May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: /usr/share/alsa/init/bcm2835:20: control element not found May 25 18:55:04 volumio volumio[1251]: No state is present for card Headphones May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: No state is present for card vc4hdmi May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 18:55:04 volumio volumio[1251]: Hardware is initialized using a generic method May 25 18:55:04 volumio volumio[1251]: No state is present for card vc4hdmi May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: No state is present for card sndrpihifiberry May 25 18:55:04 volumio volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 25 18:55:04 volumio volumio[1251]: Found hardware: "HifiberryDacp" "" "" "" "" May 25 18:55:04 volumio volumio[1251]: Hardware is initialized using a generic method May 25 18:55:04 volumio volumio[1251]: No state is present for card sndrpihifiberry May 25 18:55:04 volumio volumio[1251]: info: Output device has changed, restarting MPD May 25 18:55:04 volumio volumio[1251]: info: Output device has changed, restarting Shairport Sync May 25 18:55:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:55:04 volumio sudo[3018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 18:55:04 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:04 volumio sudo[3017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 18:55:04 volumio sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:04 volumio sudo[3018]: pam_unix(sudo:session): session closed for user root May 25 18:55:04 volumio volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 18:55:04 volumio volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 18:55:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:04 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:55:04 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:04 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 18:55:04 volumio volumio[1251]: info: Preparing to generate the ALSA configuration file May 25 18:55:04 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 18:55:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 18:55:04 volumio systemd[1]: mpd.service: Consumed 3.688s CPU time. May 25 18:55:04 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 18:55:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 18:55:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 18:55:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 18:55:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 18:55:04 volumio sudo[3028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:55:04 volumio sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:05 volumio sudo[3030]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 18:55:05 volumio sudo[3030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 18:55:05 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 25 18:55:05 volumio sudo[3030]: pam_unix(sudo:session): session closed for user root May 25 18:55:05 volumio systemd[1]: vtcs.service: Deactivated successfully. May 25 18:55:05 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 25 18:55:05 volumio sudo[3033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:55:05 volumio sudo[3033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:05 volumio sudo[3028]: pam_unix(sudo:session): session closed for user root May 25 18:55:05 volumio volumio[1251]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 18:55:05 volumio volumio[1251]: info: Reading ALSA contributions from plugins. May 25 18:55:05 volumio volumio[1251]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 25 18:55:05 volumio volumio[1251]: info: MPD Permissions set May 25 18:55:05 volumio volumio[1251]: info: Executing endpoint tc_getconfig May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio sudo[3033]: pam_unix(sudo:session): session closed for user root May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:55:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:05 volumio sudo[3043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 18:55:05 volumio sudo[3043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:05 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:55:06 volumio volumio[1251]: info: Asound.conf file unchanged, so no further update is needed May 25 18:55:06 volumio volumio[1251]: info: Output device has changed, restarting MPD May 25 18:55:06 volumio sudo[3043]: pam_unix(sudo:session): session closed for user root May 25 18:55:06 volumio volumio[1251]: info: Output device has changed, restarting Shairport Sync May 25 18:55:06 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:06 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:55:06 volumio sudo[3054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 18:55:06 volumio sudo[3054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:06 volumio sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:55:06 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:06 volumio sudo[3055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 18:55:06 volumio sudo[3055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:06 volumio sudo[3055]: pam_unix(sudo:session): session closed for user root May 25 18:55:06 volumio sudo[3058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 18:55:06 volumio sudo[3058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:06 volumio volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 18:55:06 volumio volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 18:55:06 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:06 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:55:06 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:06 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:06 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 25 18:55:06 volumio qobuz-connect[2950]: 20260525 18:55:06.764 [2950.2950] INFO SampleApp: Stopping Local configuration server May 25 18:55:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 18:55:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 18:55:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:55:06 volumio systemd[1]: shairport-sync.service: Consumed 1.559s CPU time. May 25 18:55:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:55:06 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root May 25 18:55:06 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 18:55:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 18:55:06 volumio systemd[1]: mpd.service: Consumed 1.831s CPU time. May 25 18:55:06 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 18:55:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 18:55:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 18:55:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 18:55:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 18:55:07 volumio sudo[3070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:55:07 volumio sudo[3070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:07 volumio sudo[3087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 18:55:07 volumio sudo[3087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:07 volumio sudo[3088]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 18:55:07 volumio sudo[3088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 18:55:07 volumio sudo[3088]: pam_unix(sudo:session): session closed for user root May 25 18:55:07 volumio volumio[1251]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 25 18:55:07 volumio sudo[3087]: pam_unix(sudo:session): session closed for user root May 25 18:55:07 volumio volumio[1251]: info: MPD Permissions set May 25 18:55:07 volumio volumio[1251]: info: TidalConnect service started! May 25 18:55:07 volumio qobuz-connect[2950]: 20260525 18:55:07.755 [2950.2950] INFO SampleApp: shat down connection on UNIX socket May 25 18:55:07 volumio volumio[1251]: info: TidalConnect service started! May 25 18:55:07 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:55:07 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 25 18:55:07 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:55:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:07 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:07 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 25 18:55:07 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:55:07 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:55:07 volumio sudo[3070]: pam_unix(sudo:session): session closed for user root May 25 18:55:08 volumio sudo[3054]: pam_unix(sudo:session): session closed for user root May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio sudo[3098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 18:55:08 volumio sudo[3098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:08 volumio sudo[3098]: pam_unix(sudo:session): session closed for user root May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 18:55:08 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:08 volumio volumio[1251]: info: Executing endpoint qc_getconfig May 25 18:55:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 18:55:08 volumio sudo[3108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 18:55:08 volumio sudo[3108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.794 [3100.3100] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.801 [3100.3100] INFO VolumeManager: [0x6f6058]: Setting new playback volume: 75 May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.801 [3100.3100] INFO VolumeManager: [0x6f6058]: Setting new mute state: 0 May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.801 [3100.3100] INFO AudioStreamManager: [0x6f5db0]: Setting new audio download buffer size: 1048576 May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.801 [3100.3100] INFO QobuzConnect: [0x6f6920]: Client initialized! May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.801 [3100.3100] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 25 18:55:08 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 18:55:08 volumio volumio[1251]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 18:55:08 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.884 [3100.3100] INFO LocalConfigManager: [0x6f5ad8]: Starting Local Configuration server May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.884 [3100.3100] INFO SampleApp: Starting Local configuration server May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.885 [3100.3100] INFO SampleApp: Connected to UNIX socket client 0x6e0818 May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.943 [3100.3100] INFO SampleApp: Playback volume changed: 75 May 25 18:55:08 volumio qobuz-connect[3100]: 20260525 18:55:08.984 [3100.3100] INFO SampleApp: Stopping Local configuration server May 25 18:55:08 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 25 18:55:09 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:09 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:09 volumio volumio[1251]: info: Starting Shairport Sync May 25 18:55:09 volumio sudo[3120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 18:55:09 volumio sudo[3120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:09 volumio volumio[1251]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:09 volumio volumio[1251]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 18:55:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 18:55:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:55:09 volumio systemd[1]: shairport-sync.service: Consumed 1.289s CPU time. May 25 18:55:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 18:55:09 volumio sudo[3120]: pam_unix(sudo:session): session closed for user root May 25 18:55:09 volumio volumio[1251]: info: Shairport-Sync Started May 25 18:55:10 volumio sudo[3128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 18:55:10 volumio sudo[3128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 18:55:10 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 25 18:55:10 volumio sudo[3128]: pam_unix(sudo:session): session closed for user root May 25 18:55:10 volumio volumio[1251]: info: Executing endpoint tc_getconfig May 25 18:55:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 25 18:55:10 volumio vtcs[3146]: STARTING TidalConnect services, version: 1.6.1 May 25 18:55:10 volumio vtcs[3146]: STARTED TidalConnect services. May 25 18:55:10 volumio qobuz-connect[3100]: 20260525 18:55:10.897 [3100.3100] INFO SampleApp: shat down connection on UNIX socket May 25 18:55:10 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 25 18:55:10 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:55:10 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 25 18:55:11 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:11 volumio sudo[3108]: pam_unix(sudo:session): session closed for user root May 25 18:55:11 volumio volumio[1251]: info: TidalConnect service stoped! May 25 18:55:11 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 25 18:55:11 volumio volumio[1251]: info: QobuzConnect: setDeactiveState invoked May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: info: Executing endpoint tc_connect May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 25 18:55:11 volumio volumio[1251]: info: Connecting to TidalConnect May 25 18:55:11 volumio volumio[1251]: info: Executing endpoint qc_getconfig May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.745 [3159.3159] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.752 [3159.3159] INFO VolumeManager: [0x12ba058]: Setting new playback volume: 75 May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.752 [3159.3159] INFO VolumeManager: [0x12ba058]: Setting new mute state: 0 May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.752 [3159.3159] INFO AudioStreamManager: [0x12b9db0]: Setting new audio download buffer size: 1048576 May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.753 [3159.3159] INFO QobuzConnect: [0x12ba920]: Client initialized! May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.753 [3159.3159] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.832 [3159.3159] INFO LocalConfigManager: [0x12b9ad8]: Starting Local Configuration server May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.832 [3159.3159] INFO SampleApp: Starting Local configuration server May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.833 [3159.3159] INFO SampleApp: Connected to UNIX socket client 0x12a4818 May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 25 18:55:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:11 volumio mpd[3096]: 2026-05-25T18:55:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 18:55:11 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 25 18:55:11 volumio sudo[3017]: pam_unix(sudo:session): session closed for user root May 25 18:55:11 volumio qobuz-connect[3159]: 20260525 18:55:11.895 [3159.3159] INFO SampleApp: Playback volume changed: 75 May 25 18:55:11 volumio sudo[3058]: pam_unix(sudo:session): session closed for user root May 25 18:55:11 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:11 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: 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} May 25 18:55:11 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 25 18:55:11 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 25 18:55:11 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 25 18:55:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:11 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:11 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: info: No code May 25 18:55:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 25 18:55:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: 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} May 25 18:55:12 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 25 18:55:12 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 25 18:55:12 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 25 18:55:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: No code May 25 18:55:12 volumio volumio[1251]: info: CoreStateMachine::pushState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device May 25 18:55:12 volumio volumio[1251]: info: MRS: Pushing multiroomSync output May 25 18:55:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 25 18:55:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 25 18:55:12 volumio volumio[1251]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 18:55:12 volumio volumio[1251]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 18:55:12 volumio volumio[1251]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:12 volumio volumio[1251]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 18:55:12 volumio volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 18:55:12 volumio volumio[1251]: Error: ENOENT: no such file or directory, unlink '/tmp/bAXJqwDopbiUndzyrhGRh5RCYYxaNS1joq47zgjubflhMHC71ZeACF7BPJeuvm3ZKKk4cjPRMpJFYb1AaJCEESuF5EvhzjQdAXBs' May 25 18:55:12 volumio volumio[1251]: at Object.unlinkSync (node:fs:1899:3) May 25 18:55:12 volumio volumio[1251]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11265) May 25 18:55:12 volumio volumio[1251]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) May 25 18:55:12 volumio volumio[1251]: at TidalConnectClient.emit (node:events:514:28) May 25 18:55:12 volumio volumio[1251]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44713) May 25 18:55:12 volumio volumio[1251]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41683 May 25 18:55:12 volumio volumio[1251]: at Array.forEach () May 25 18:55:12 volumio volumio[1251]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41561) May 25 18:55:12 volumio volumio[1251]: at Socket.emit (node:events:514:28) May 25 18:55:12 volumio volumio[1251]: at addChunk (node:internal/streams/readable:343:12) May 25 18:55:12 volumio volumio[1251]: at readableAddChunk (node:internal/streams/readable:316:9) May 25 18:55:12 volumio volumio[1251]: at Readable.push (node:internal/streams/readable:253:10) May 25 18:55:12 volumio volumio[1251]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { May 25 18:55:12 volumio volumio[1251]: errno: -2, May 25 18:55:12 volumio volumio[1251]: syscall: 'unlink', May 25 18:55:12 volumio volumio[1251]: code: 'ENOENT', May 25 18:55:12 volumio volumio[1251]: path: '/tmp/bAXJqwDopbiUndzyrhGRh5RCYYxaNS1joq47zgjubflhMHC71ZeACF7BPJeuvm3ZKKk4cjPRMpJFYb1AaJCEESuF5EvhzjQdAXBs' May 25 18:55:12 volumio volumio[1251]: } May 25 18:55:12 volumio volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 18:55:19 volumio sudo[3207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 18:54' May 25 18:55:19 volumio sudo[3207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"