Mar 26 07:51:00 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 26 07:51:00 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 26 07:51:00 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 26 07:51:00 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:51:00 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:51:00 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:51:00 volumio volumio[1120]: Found hardware: "RPi-simple" "" "" "" "" Mar 26 07:51:00 volumio volumio[1120]: Hardware is initialized using a generic method Mar 26 07:51:00 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:51:00 volumio volumio[1120]: info: Output device has changed, restarting MPD Mar 26 07:51:00 volumio volumio[1120]: info: Output device has changed, restarting Shairport Sync Mar 26 07:51:00 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:00 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:51:00 volumio sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 07:51:00 volumio volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 07:51:00 volumio volumio[1120]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 07:51:00 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:00 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:00 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:00 volumio sudo[1992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 07:51:00 volumio sudo[1992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:00 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:51:00 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:00 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:00 volumio volumio[1120]: info: Preparing to generate the ALSA configuration file Mar 26 07:51:00 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 07:51:01 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 07:51:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 07:51:01 volumio systemd[1]: mpd.service: Consumed 5.255s CPU time. Mar 26 07:51:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 07:51:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 07:51:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 07:51:01 volumio sudo[2002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:51:01 volumio sudo[2002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:01 volumio sudo[2006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:51:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 07:51:01 volumio sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 07:51:01 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 26 07:51:01 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 26 07:51:01 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 26 07:51:01 volumio sudo[2002]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:01 volumio sudo[2010]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 07:51:01 volumio sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 07:51:01 volumio sudo[2010]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:01 volumio volumio[1120]: info: Successfully retrieved User Subscription From TIDAL Mar 26 07:51:01 volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:01 volumio volumio[1120]: info: Adding TIDAL to Browse Sources Mar 26 07:51:01 volumio volumio[1120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 07:51:01 volumio sudo[2015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:51:01 volumio sudo[2015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:01 volumio volumio[1120]: info: [1774529461759] CoreMusicLibrary::Adding element TIDAL Mar 26 07:51:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 07:51:01 volumio volumio[1120]: Cannot find translation for source TIDAL Mar 26 07:51:01 volumio sudo[2015]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:01 volumio volumio[1120]: info: Adding TIDAL REST API Endpoints Mar 26 07:51:02 volumio sudo[2020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:51:02 volumio sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:02 volumio volumio[1120]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 07:51:02 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 07:51:02 volumio qobuz-connect[1902]: 20260326 07:51:02.180 [1902.1902] INFO SampleApp: Stopping Local configuration server Mar 26 07:51:02 volumio volumio[1120]: info: Reading ALSA contributions from plugins. Mar 26 07:51:02 volumio qobuz-connect[1902]: 20260326 07:51:02.332 [1902.1902] INFO SampleApp: shat down connection on UNIX socket Mar 26 07:51:02 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 07:51:02 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 07:51:02 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 07:51:02 volumio sudo[2020]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:02 volumio volumio[1120]: info: MPD Permissions set Mar 26 07:51:02 volumio volumio[1120]: info: Executing endpoint tc_getconfig Mar 26 07:51:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 26 07:51:02 volumio volumio[1120]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 26 07:51:02 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:51:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:02 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:03 volumio volumio[1120]: info: TidalConnect service started! Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:03 volumio volumio[1120]: info: Executing endpoint qc_getconfig Mar 26 07:51:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.206 [2022.2022] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.221 [2022.2022] INFO VolumeManager: [0xc86a48]: Setting new playback volume: 75 Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.221 [2022.2022] INFO VolumeManager: [0xc86a48]: Setting new mute state: 0 Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.222 [2022.2022] INFO AudioStreamManager: [0xc867a0]: Setting new audio download buffer size: 1048576 Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.222 [2022.2022] INFO QobuzConnect: [0xc87310]: Client initialized! Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.222 [2022.2022] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.273 [2022.2022] INFO LocalConfigManager: [0xc864c8]: Starting Local Configuration server Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.273 [2022.2022] INFO SampleApp: Starting Local configuration server Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.274 [2022.2022] INFO SampleApp: Connected to UNIX socket client 0xc71818 Mar 26 07:51:04 volumio qobuz-connect[2022]: 20260326 07:51:04.356 [2022.2022] INFO SampleApp: Playback volume changed: 75 Mar 26 07:51:04 volumio volumio[1120]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 26 07:51:04 volumio volumio[1120]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 26 07:51:05 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:05 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:05 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:51:05 volumio volumio[1120]: info: Starting Shairport Sync Mar 26 07:51:05 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:51:05 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:05 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 07:51:05 volumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 07:51:05 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 07:51:05 volumio volumio[1120]: info: Asound.conf file written Mar 26 07:51:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 07:51:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:51:05 volumio systemd[1]: shairport-sync.service: Consumed 1.597s CPU time. Mar 26 07:51:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:51:06 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:06 volumio sudo[2062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 26 07:51:06 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:06 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:07 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 26 07:51:07 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 26 07:51:07 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 26 07:51:07 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:51:07 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:51:07 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:51:07 volumio volumio[1120]: Found hardware: "RPi-simple" "" "" "" "" Mar 26 07:51:07 volumio volumio[1120]: Hardware is initialized using a generic method Mar 26 07:51:07 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:51:07 volumio volumio[1120]: info: Output device has changed, restarting MPD Mar 26 07:51:07 volumio mpd[2018]: 2026-03-26T07:51:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 26 07:51:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 26 07:51:07 volumio sudo[1992]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:07 volumio sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 07:51:07 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:07 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:07 volumio volumio[1120]: info: Output device has changed, restarting Shairport Sync Mar 26 07:51:07 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:07 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:51:07 volumio volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 07:51:07 volumio volumio[1120]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 07:51:07 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:07 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:51:07 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:07 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:07 volumio sudo[2087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 07:51:07 volumio sudo[2087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:08 volumio sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:51:08 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 07:51:08 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 07:51:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 07:51:08 volumio systemd[1]: mpd.service: Consumed 5.558s CPU time. Mar 26 07:51:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 07:51:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 07:51:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 07:51:08 volumio sudo[2098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:51:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 07:51:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 07:51:08 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:08 volumio volumio5-onboarding[1769]: failed to bootstrap state: failed to get logged user: could not authenticate with MyVolumio token: failed to sign in with custom token: context deadline exceeded Mar 26 07:51:08 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 26 07:51:08 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 26 07:51:08 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Mar 26 07:51:08 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:08 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 26 07:51:08 volumio volumio[1120]: info: MPD Permissions set Mar 26 07:51:08 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 26 07:51:08 volumio sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:51:08 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:08 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:08.974-05:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 26 07:51:09 volumio volumio[1120]: info: Shairport-Sync Started Mar 26 07:51:09 volumio sudo[2107]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 07:51:09 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 07:51:09 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:09 volumio volumio[1120]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:51:09 volumio volumio[1120]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:51:09 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:09 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:09 volumio sudo[2122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:51:09 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:09 volumio volumio[1120]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:09 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 07:51:09 volumio qobuz-connect[2022]: 20260326 07:51:09.766 [2022.2022] INFO SampleApp: Stopping Local configuration server Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:51:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:51:10 volumio volumio[1120]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Mar 26 07:51:10 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:10 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:10 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:10 volumio qobuz-connect[2022]: 20260326 07:51:10.300 [2022.2022] INFO SampleApp: shat down connection on UNIX socket Mar 26 07:51:10 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 07:51:10 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 07:51:10 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 07:51:10 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:10 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:10.423-05:00 level=INFO msg="system info for 90f085ae085ad0ce3bd093b4c2e1e61a" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 26 07:51:10 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:10.453-05:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 26 07:51:10 volumio volumio[1120]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 26 07:51:10 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:10 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:10 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:10 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:10 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:10 volumio sudo[2133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 26 07:51:10 volumio volumio[1120]: info: Starting Shairport Sync Mar 26 07:51:10 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:10 volumio volumio-remote-updater[679]: Test mode disabled Mar 26 07:51:10 volumio volumio-remote-updater[679]: Alpha mode disabled Mar 26 07:51:10 volumio volumio-remote-updater[679]: Alpha legacy test mode disabled Mar 26 07:51:11 volumio volumio[1120]: info: Executing endpoint qc_getconfig Mar 26 07:51:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 26 07:51:11 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 26 07:51:11 volumio sudo[2137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 07:51:11 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:11 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.446 [2134.2134] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.458 [2134.2134] INFO VolumeManager: [0x2255a48]: Setting new playback volume: 75 Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.458 [2134.2134] INFO VolumeManager: [0x2255a48]: Setting new mute state: 0 Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.458 [2134.2134] INFO AudioStreamManager: [0x22557a0]: Setting new audio download buffer size: 1048576 Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.458 [2134.2134] INFO QobuzConnect: [0x2256310]: Client initialized! Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.458 [2134.2134] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.518 [2134.2134] INFO LocalConfigManager: [0x22554c8]: Starting Local Configuration server Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.518 [2134.2134] INFO SampleApp: Starting Local configuration server Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.519 [2134.2134] INFO SampleApp: Connected to UNIX socket client 0x2240818 Mar 26 07:51:11 volumio volumio[1120]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 26 07:51:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 26 07:51:11 volumio volumio[1120]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 26 07:51:11 volumio volumio[1120]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 26 07:51:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:11 volumio qobuz-connect[2134]: 20260326 07:51:11.609 [2134.2134] INFO SampleApp: Playback volume changed: 75 Mar 26 07:51:11 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:11 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 07:51:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 07:51:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 07:51:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:51:11 volumio systemd[1]: shairport-sync.service: Consumed 1.544s CPU time. Mar 26 07:51:11 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:51:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:51:11 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:11 volumio volumio[1120]: info: Shairport-Sync Started Mar 26 07:51:12 volumio volumio[1120]: info: Executing endpoint tc_getconfig Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 26 07:51:12 volumio vtcs[2143]: STARTING TidalConnect services, version: 1.6.1 Mar 26 07:51:12 volumio vtcs[2143]: STARTED TidalConnect services. Mar 26 07:51:12 volumio volumio[1120]: info: Executing endpoint tc_connect Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 26 07:51:12 volumio volumio[1120]: info: Connecting to TidalConnect Mar 26 07:51:12 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 26 07:51:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:12 volumio volumio[1120]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 26 07:51:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:12 volumio volumio[1120]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Mar 26 07:51:13 volumio volumio[1120]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:51:13 volumio volumio[1120]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:51:13 volumio sudo[2186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 26 07:51:13 volumio sudo[2186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:13 volumio sudo[2186]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:13 volumio mpd[2121]: 2026-03-26T07:51:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 26 07:51:13 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 26 07:51:13 volumio volumio[1120]: error: updateQueue error: null Mar 26 07:51:13 volumio sudo[2087]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:13 volumio kernel: hwmon hwmon1: Voltage normalised Mar 26 07:51:14 volumio volumio[1120]: error: updateQueue error: null Mar 26 07:51:14 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:14.088-05:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Mar 26 07:51:14 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:14 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:14 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 07:51:14 volumio volumio[1120]: info: TidalConnect service started! Mar 26 07:51:14 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:14 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 07:51:15 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:15.900-05:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=lcjUWobgE7WesWIg0pQDavPHYhD3 tokenExpiry=2026-03-26T08:51:15.900-05:00 Mar 26 07:51:16 volumio volumio[1120]: info: TidalConnect service started! Mar 26 07:51:18 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:18 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:18 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:18 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:18 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:18 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:18 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:18 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 07:51:18 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 07:51:18 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:18.405-05:00 level=INFO msg="enabling local network discovery" Mar 26 07:51:18 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:18.426-05:00 level=INFO msg="enabling BLE discovery" Mar 26 07:51:18 volumio bluealsa[905]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4E_B9_37_AE_E7_F5, ...) Mar 26 07:51:19 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:19.535-05:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 07:51:19 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:19.661-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.20.103:37152 Mar 26 07:51:20 volumio sudo[2214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 07:51:20 volumio sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 07:51:20 volumio sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:20 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:21 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:21 volumio sudo[2212]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:21 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.20.106 from 192.168.20.103 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 50 Build/V1UWS35H.63-14-6-4-2-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 26 07:51:21 volumio sudo[2220]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 07:51:21 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:21 volumio sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 07:51:21 volumio sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:21 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:21 volumio sudo[2218]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:21 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.20.106 from 192.168.20.103 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 50 Build/V1UWS35H.63-14-6-4-2-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:22 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 07:51:22 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 07:51:22 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:22 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:22 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:22 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:22 volumio volumio[1120]: info: Listing playlists Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:51:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 07:51:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:23 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:23 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:23 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 07:51:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:24 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:24 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:24 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:24 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:27 volumio volumio[1120]: info: CoreCommandRouter::volumioGetQueue Mar 26 07:51:27 volumio volumio[1120]: info: CoreStateMachine::getQueue Mar 26 07:51:27 volumio volumio[1120]: info: CorePlayQueue::getQueue Mar 26 07:51:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 07:51:32 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Mar 26 07:51:33 volumio volumio[1120]: info: browseTIDALUri took 971 milliseconds Mar 26 07:51:33 volumio volumio[1120]: info: Preload queue cleared Mar 26 07:51:33 volumio volumio[1120]: info: Preload queue cleared Mar 26 07:51:34 volumio sudo[2241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 07:51:34 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:34 volumio sudo[2241]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:34 volumio sudo[2251]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 07:51:34 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:34 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:34 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.20.106 from 192.168.20.103 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 50 Build/V1UWS35H.63-14-6-4-2-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:34 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 07:51:34 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 07:51:34 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:34 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:34 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:34 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:34 volumio volumio[1120]: info: Listing playlists Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 07:51:34 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:51:35 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 07:51:35 volumio sudo[2262]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 07:51:35 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:35 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:35 volumio sudo[2265]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 07:51:35 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:51:35 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Mar 26 07:51:35 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.20.106 from 192.168.20.103 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 50 Build/V1UWS35H.63-14-6-4-2-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 07:51:35 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 07:51:35 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 07:51:35 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:36 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::volumioGetQueue Mar 26 07:51:36 volumio volumio[1120]: info: CoreStateMachine::getQueue Mar 26 07:51:36 volumio volumio[1120]: info: CorePlayQueue::getQueue Mar 26 07:51:36 volumio volumio[1120]: info: Listing playlists Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 07:51:36 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 07:51:36 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:36 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:36 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:36 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 07:51:36 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Mar 26 07:51:37 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:37 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:37 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:37 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:37 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:37 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:37 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:37 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:37 volumio volumio[1120]: info: browseTIDALUri took 882 milliseconds Mar 26 07:51:37 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 07:51:37 volumio volumio[1120]: info: Preload queue cleared Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/5274607 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/331560 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/1442659 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/58990486 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/76773928 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/1110429 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/1618338 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/10813618 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/22248418 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/17977589 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/13339001 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/7984974 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/165813923 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/115986972 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/19741499 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/10582679 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/29862937 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/9017285 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/5274610 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/14165832 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/14165834 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/17805737 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/14165837 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/16205208 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/9979808 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/125530706 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/1783276 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/1442816 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/136338046 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/4104035 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/108123398 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/33217253 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/45203154 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/107507268 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/2870959 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/23152914 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/300672150 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/3159850 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/4949703 Mar 26 07:51:37 volumio volumio[1120]: info: Preloading song: tidal://song/77702881 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/18251803 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/2846326 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/26828569 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/47223985 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/20115564 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/57968175 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/45144506 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/68883384 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/39249713 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/65931865 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/67237704 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/63442986 Mar 26 07:51:38 volumio volumio[1120]: info: Preloading song: tidal://song/186818709 Mar 26 07:51:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:51:38 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:38 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:38 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:38 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/5274607 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/331560 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/1442659 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/58990486 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/76773928 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/1110429 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/1618338 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: explodeTIDALUri took 292 milliseconds Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/10813618 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/22248418 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/17977589 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/13339001 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/7984974 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: explodeTIDALUri took 278 milliseconds Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/165813923 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/115986972 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/19741499 in service tidal Mar 26 07:51:38 volumio volumio[1120]: info: Exploding uri tidal://song/10582679 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: explodeTIDALUri took 454 milliseconds Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/29862937 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/9017285 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/5274610 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/14165832 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/14165834 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/17805737 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/14165837 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: Exploding uri tidal://song/16205208 in service tidal Mar 26 07:51:39 volumio volumio[1120]: info: explodeTIDALUri took 1109 milliseconds Mar 26 07:51:39 volumio volumio[1120]: info: explodeTIDALUri took 533 milliseconds Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/9979808 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/125530706 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/1783276 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/1442816 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/136338046 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/4104035 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/108123398 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/33217253 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/45203154 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/107507268 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/2870959 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/23152914 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/300672150 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/3159850 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/4949703 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/77702881 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/18251803 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/2846326 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/26828569 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: Exploding uri tidal://song/47223985 in service tidal Mar 26 07:51:40 volumio volumio[1120]: info: explodeTIDALUri took 1333 milliseconds Mar 26 07:51:40 volumio volumio[1120]: info: explodeTIDALUri took 1452 milliseconds Mar 26 07:51:40 volumio volumio[1120]: info: explodeTIDALUri took 2282 milliseconds Mar 26 07:51:40 volumio volumio[1120]: info: explodeTIDALUri took 2507 milliseconds Mar 26 07:51:41 volumio volumio[1120]: info: Preload queue cleared Mar 26 07:51:41 volumio volumio[1120]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 07:51:41 volumio volumio[1120]: info: CoreStateMachine::ClearQueue Mar 26 07:51:41 volumio volumio[1120]: info: CoreStateMachine::stop Mar 26 07:51:41 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:51:41 volumio volumio[1120]: info: CorePlayQueue::clearPlayQueue Mar 26 07:51:41 volumio volumio[1120]: info: CorePlayQueue::saveQueue Mar 26 07:51:41 volumio volumio[1120]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:51:41 volumio volumio[1120]: info: CoreStateMachine::addQueueItems Mar 26 07:51:41 volumio volumio[1120]: info: CorePlayQueue::addQueueItems Mar 26 07:51:41 volumio volumio[1120]: info: Preload queue cleared Mar 26 07:51:41 volumio volumio[1120]: info: Adding Item to queue: tidal://playlist/1b43721f-db7e-4976-9a02-7c08012aca57 Mar 26 07:51:41 volumio volumio[1120]: info: Exploding uri tidal://playlist/1b43721f-db7e-4976-9a02-7c08012aca57 in service tidal Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 2323 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 2336 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 2284 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 2346 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4189 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4014 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4259 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4013 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4027 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4193 milliseconds Mar 26 07:51:42 volumio volumio[1120]: info: explodeTIDALUri took 4177 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4435 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4287 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4307 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4306 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4305 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4301 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3246 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3253 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3292 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3256 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3206 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3208 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3159 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3214 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3137 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3145 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4814 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 4363 milliseconds Mar 26 07:51:43 volumio volumio[1120]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [17805737] not found"} Mar 26 07:51:43 volumio volumio[1120]: error: Commandrouter: Cannot explode uri tidal://song/17805737 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [17805737] not found"} Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3133 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3218 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3427 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3579 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3477 milliseconds Mar 26 07:51:43 volumio volumio[1120]: info: explodeTIDALUri took 3536 milliseconds Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::volumioPlay Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::play index undefined Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:44 volumio volumio[1120]: info: explodeTIDALUri took 3131 milliseconds Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::saveQueue Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::updateTrackBlock Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::getTrackBlock Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 26 07:51:44 volumio volumio[1120]: info: CoreCommandRouter::volumioPlay Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::play index 0 Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::stop Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::play index undefined Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:44 volumio volumio[1120]: info: CoreStateMachine::startPlaybackTimer Mar 26 07:51:44 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:44 volumio volumio[1120]: info: [1774529504797] ControllerTidal::clearAddPlayTrack Mar 26 07:51:44 volumio volumio[1120]: info: Getting stream with soundQuality HI_RES Mar 26 07:51:45 volumio volumio[1120]: info: getStreamUrl took 202 milliseconds Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand stop took 29 milliseconds Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces system playlist update Mar 26 07:51:45 volumio volumio[1120]: info: Ignoring MPD Status Update Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand clear took 45 milliseconds Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYzQ0NWZkZTVlNjNhZTgzOWNjNWIxMGRkNGE3Yzk1Zl82Mi5tcDQ/0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==" Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces system playlist update Mar 26 07:51:45 volumio volumio[1120]: info: Ignoring MPD Status Update Mar 26 07:51:45 volumio volumio[1120]: error: updateQueue error: null Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces system playlist update Mar 26 07:51:45 volumio volumio[1120]: info: Ignoring MPD Status Update Mar 26 07:51:45 volumio volumio[1120]: info: ------------------------------ 59ms Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYzQ0NWZkZTVlNjNhZTgzOWNjNWIxMGRkNGE3Yzk1Zl82Mi5tcDQ/0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==" took 36 milliseconds Mar 26 07:51:45 volumio volumio[1120]: info: ------------------------------ 36ms Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand play Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces system playlist update Mar 26 07:51:45 volumio volumio[1120]: info: Ignoring MPD Status Update Mar 26 07:51:45 volumio volumio[1120]: info: ------------------------------ 55ms Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand play took 52 milliseconds Mar 26 07:51:45 volumio volumio[1120]: info: ------------------------------ 29ms Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces state update: player Mar 26 07:51:45 volumio volumio[1120]: info: ControllerMpd::getState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces state update: player Mar 26 07:51:45 volumio volumio[1120]: info: ControllerMpd::getState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces state update: player Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand status took 28 milliseconds Mar 26 07:51:45 volumio volumio[1120]: info: ControllerMpd::getState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand status took 27 milliseconds Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::parseState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::parseState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:51:45 volumio volumio[1120]: info: Mar 26 07:51:45 volumio volumio[1120]: ---------------------------- MPD announces state update: player Mar 26 07:51:45 volumio volumio[1120]: info: ControllerMpd::getState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand status took 23 milliseconds Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 26 07:51:45 volumio volumio[1120]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::parseState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::parseState Mar 26 07:51:45 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:51:45 volumio volumio[1120]: info: ControllerMpd::pushState Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 26 07:51:45 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:45 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYzQ0NWZkZTVlNjNhZTgzOWNjNWIxMGRkNGE3Yzk1Zl82Mi5tcDQ/0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","trackType":"tidal"} Mar 26 07:51:45 volumio volumio[1120]: verbose: CURRENT POSITION 0 Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus stop Mar 26 07:51:45 volumio volumio[1120]: info: ControllerMpd::pushState Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 26 07:51:45 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:45 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYzQ0NWZkZTVlNjNhZTgzOWNjNWIxMGRkNGE3Yzk1Zl82Mi5tcDQ/0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","trackType":"tidal"} Mar 26 07:51:45 volumio volumio[1120]: verbose: CURRENT POSITION 0 Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:51:45 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:45 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:45 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:45 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:45 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:45 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:45 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:45 volumio volumio[1120]: info: ------------------------------ 229ms Mar 26 07:51:45 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:45.960-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 07:51:45 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:45.962-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:51:46 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:46.041-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 07:51:46 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:46.042-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:51:46 volumio volumio[1120]: info: ------------------------------ 731ms Mar 26 07:51:46 volumio volumio[1120]: info: sendMpdCommand playlistinfo took 700 milliseconds Mar 26 07:51:46 volumio volumio[1120]: info: sendMpdCommand playlistinfo took 690 milliseconds Mar 26 07:51:46 volumio volumio[1120]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:51:46 volumio volumio[1120]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:51:46 volumio volumio[1120]: info: ControllerMpd::pushState Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 26 07:51:46 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:46 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYzQ0NWZkZTVlNjNhZTgzOWNjNWIxMGRkNGE3Yzk1Zl82Mi5tcDQ/0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","trackType":"tidal"} Mar 26 07:51:46 volumio volumio[1120]: verbose: CURRENT POSITION 0 Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:51:46 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:46 volumio volumio[1120]: info: ControllerMpd::pushState Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 26 07:51:46 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:51:46 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYzQ0NWZkZTVlNjNhZTgzOWNjNWIxMGRkNGE3Yzk1Zl82Mi5tcDQ/0.flac?token=1774533104~YTQyYjU2MTkxNWZjYjM0ZjRjOGJkOGU5NTVkZTgxODkwMjZmNWE0YQ==","trackType":"tidal"} Mar 26 07:51:46 volumio volumio[1120]: verbose: CURRENT POSITION 0 Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:51:46 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:46 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:51:46 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:51:46 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.111-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=873 volume=100 Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.112-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.206-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=873 volume=100 Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.206-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.244-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=873 volume=100 Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.244-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.273-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=873 volume=100 Mar 26 07:51:47 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:47.274-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:51:47 volumio volumio[1120]: info: ------------------------------ 1922ms Mar 26 07:51:47 volumio volumio[1120]: info: ------------------------------ 1900ms Mar 26 07:51:48 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:51:53 volumio volumio[1120]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 26 07:51:53 volumio volumio[1120]: info: Received Get System Version Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 07:51:53 volumio volumio[1120]: info: Received Get System Info Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:51:53 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:51:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:51:59 volumio volumio5-onboarding[2111]: time=2026-03-26T07:51:59.888-05:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 26 07:52:00 volumio dbus-daemon[662]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.40" (uid=0 pid=2111 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=764 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 26 07:52:01 volumio sudo[2304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 07:52:01 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:01 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:01 volumio sudo[2306]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 07:52:01 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:01 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:01 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.20.106 from 192.168.20.103 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 50 Build/V1UWS35H.63-14-6-4-2-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 07:52:01 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 07:52:01 volumio volumio[1120]: info: Received Get System Info Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:52:01 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:01 volumio volumio[1120]: info: Listing playlists Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:52:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 07:52:01 volumio sudo[2310]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 07:52:01 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:01 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:02 volumio sudo[2312]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 07:52:02 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:02 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:02 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.20.106 from 192.168.20.103 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 50 Build/V1UWS35H.63-14-6-4-2-5; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 07:52:02 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 07:52:02 volumio volumio[1120]: info: Received Get System Info Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:52:02 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:02 volumio volumio[1120]: info: Listing playlists Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:52:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 07:52:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:52:03 volumio volumio[1120]: info: Received Get System Info Mar 26 07:52:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:52:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:52:03 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:52:03 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:03 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:52:04 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 07:52:04 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 07:52:04 volumio volumio[1120]: info: Received Get System Info Mar 26 07:52:04 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:52:04 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:52:04 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:52:04 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:04 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:52:09 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 07:52:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 07:52:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 07:52:09 volumio volumio[1120]: info: Getting Alsa Cards List without I2S DAC Mar 26 07:52:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 26 07:52:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 07:52:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 07:52:20 volumio volumio[1120]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 26 07:52:20 volumio volumio[1120]: info: Received Get System Version Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 07:52:20 volumio volumio[1120]: info: Received Get System Info Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 07:52:20 volumio volumio[1120]: info: Discovery: Getting this device information Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 07:52:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Mar 26 07:52:20 volumio volumio[1120]: info: MRS: Starting browser stream Mar 26 07:52:20 volumio volumio[1120]: info: MRS: Setting this device as Streaming Server Mar 26 07:52:20 volumio volumio[1120]: info: Mar 26 07:52:20 volumio volumio[1120]: [1774529540445] ---------------------------- MRS: Setting Streaming Server Mar 26 07:52:20 volumio volumio[1120]: info: Enabled audio output: browserPlayback Mar 26 07:52:20 volumio volumio[1120]: info: MRS: enable multiroom server output Mar 26 07:52:20 volumio volumio[1120]: info: MRS: Set multiroom target PCM to volumioMultiRoom Mar 26 07:52:20 volumio volumio[1120]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Mar 26 07:52:20 volumio volumio[1120]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 26 07:52:20 volumio volumio[1120]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Mar 26 07:52:20 volumio volumio[1120]: info: MRS: STARTING volumioStreaming Mar 26 07:52:20 volumio sudo[2359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 26 07:52:20 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:20 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:21 volumio sudo[2362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Mar 26 07:52:21 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:21 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Mar 26 07:52:21 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:21 volumio volumio[1120]: info: MRS: volumioStreaming STARTED Mar 26 07:52:21 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 26 07:52:21 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:21 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Mar 26 07:52:30 volumio volumio[1120]: info: MRS: Starting browser stream Mar 26 07:52:30 volumio volumio[1120]: info: MRS: Setting this device as single Mar 26 07:52:30 volumio volumio[1120]: info: Mar 26 07:52:30 volumio volumio[1120]: [1774529550137] ---------------------------- Setting Multiroom Single Mar 26 07:52:30 volumio volumio[1120]: info: MRS: disable multiroom output Mar 26 07:52:30 volumio volumio[1120]: info: MRS: Set multiroom target PCM to volumioDiscard Mar 26 07:52:30 volumio volumio[1120]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Mar 26 07:52:30 volumio volumio[1120]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 26 07:52:30 volumio volumio[1120]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Mar 26 07:52:31 volumio volumio[1120]: info: MRS: STOPPING volumioStreaming Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 07:52:31 volumio sudo[2390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 26 07:52:31 volumio sudo[2392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream4.ts /tmp/hls/stream5.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts Mar 26 07:52:31 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:31 volumio sudo[2390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:31 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 07:52:31 volumio volumio[1120]: info: Getting Alsa Cards List without I2S DAC Mar 26 07:52:31 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Mar 26 07:52:31 volumio systemd[1]: volumioStreaming.service: Killing process 2367 (ffmpeg) with signal SIGKILL. Mar 26 07:52:31 volumio systemd[1]: volumioStreaming.service: Killing process 2369 (ffmpeg) with signal SIGKILL. Mar 26 07:52:31 volumio systemd[1]: volumioStreaming.service: Killing process 2371 (n/a) with signal SIGKILL. Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:31 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. Mar 26 07:52:31 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Mar 26 07:52:31 volumio systemd[1]: volumioStreaming.service: Consumed 4.307s CPU time. Mar 26 07:52:31 volumio sudo[2390]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 26 07:52:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 07:52:31 volumio volumio[1120]: info: MRS: volumioStreaming STOPPED Mar 26 07:52:31 volumio volumio[1120]: info: MRS: Removed streaming files Mar 26 07:52:33 volumio ntpd[918]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 26 07:52:33 volumio ntpd[918]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 26 07:52:33 volumio ntpd[918]: DNS: Pool skipping: 200.25.20.50 Mar 26 07:52:33 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.11 Mar 26 07:52:33 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.17 Mar 26 07:52:33 volumio ntpd[918]: DNS: Pool skipping: 186.144.41.229 Mar 26 07:52:33 volumio ntpd[918]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 26 07:52:34 volumio ntpd[918]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 26 07:52:34 volumio ntpd[918]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Mar 26 07:52:34 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.11 Mar 26 07:52:34 volumio ntpd[918]: DNS: Pool skipping: 200.25.20.50 Mar 26 07:52:34 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.17 Mar 26 07:52:34 volumio ntpd[918]: DNS: Pool skipping: 186.144.41.5 Mar 26 07:52:34 volumio ntpd[918]: DNS: Pool skipping: 2803:bc40:8160::3 Mar 26 07:52:34 volumio ntpd[918]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Mar 26 07:52:35 volumio ntpd[918]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 26 07:52:35 volumio ntpd[918]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Mar 26 07:52:35 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.17 Mar 26 07:52:35 volumio ntpd[918]: DNS: Pool skipping: 200.25.20.50 Mar 26 07:52:35 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.11 Mar 26 07:52:35 volumio ntpd[918]: DNS: Pool skipping: 186.144.41.229 Mar 26 07:52:35 volumio ntpd[918]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Mar 26 07:52:36 volumio ntpd[918]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 26 07:52:36 volumio ntpd[918]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Mar 26 07:52:36 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.11 Mar 26 07:52:36 volumio ntpd[918]: DNS: Pool skipping: 200.25.20.50 Mar 26 07:52:36 volumio ntpd[918]: DNS: Pool skipping: 200.25.3.17 Mar 26 07:52:36 volumio ntpd[918]: DNS: Pool skipping: 186.144.41.229 Mar 26 07:52:36 volumio ntpd[918]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 07:52:38 volumio volumio[1120]: info: Getting Alsa Cards List without I2S DAC Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 26 07:52:38 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 07:52:52 volumio volumio[1120]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:52 volumio volumio[1120]: info: Restoring Previous Volume level: 100 false true Mar 26 07:52:52 volumio volumio[1120]: info: VolumeController::SetAlsaVolume100 Mar 26 07:52:52 volumio volumio[1120]: info: Enable softmixer device for audio device number 3 Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::volumioStop Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::stop Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::stPlaybackTimer Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::updateTrackBlock Mar 26 07:52:52 volumio volumio[1120]: info: CorePlayQueue::getTrackBlock Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:52 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:52 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:52 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:52 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::serviceStop Mar 26 07:52:52 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Mar 26 07:52:52 volumio volumio[1120]: info: CoreCommandRouter::serviceStop Mar 26 07:52:52 volumio volumio[1120]: info: [1774529572982] ControllerTidal::stop Mar 26 07:52:52 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 07:52:52 volumio volumio[1120]: info: ControllerMpd::stop Mar 26 07:52:52 volumio volumio[1120]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:52:52 volumio volumio[1120]: info: Enable softmixer device for audio device undefined Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 26 07:52:53 volumio volumio[1120]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 26 07:52:53 volumio volumio[1120]: info: Disabling external Volume Control Mar 26 07:52:53 volumio volumio[1120]: info: Output device has changed, restarting MPD Mar 26 07:52:53 volumio volumio[1120]: info: Output device has changed, restarting Shairport Sync Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:53 volumio sudo[2460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 07:52:53 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio sudo[2462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 07:52:53 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:53 volumio volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:53 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:53 volumio vtcs[2143]: [2026-03-26 07:52:53.127] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 26 07:52:53 volumio vtcs[2143]: [2026-03-26 07:52:53.128] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 26 07:52:53 volumio vtcs[2143]: [2026-03-26 07:52:53.128] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 26 07:52:53 volumio volumio[1120]: info: Volume configurations have been set Mar 26 07:52:53 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 07:52:53 volumio sudo[2472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:53 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio sudo[2474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:53 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 26 07:52:53 volumio volumio[1120]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 26 07:52:53 volumio volumio[1120]: info: Disabling external Volume Control Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 07:52:53 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:53 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 07:52:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 07:52:53 volumio systemd[1]: mpd.service: Consumed 7.335s CPU time. Mar 26 07:52:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 07:52:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 07:52:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.387-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.389-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.404-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.405-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:53 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 07:52:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.475-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.477-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:53 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 26 07:52:53 volumio volumio[1120]: error: MPD error: Error: write EPIPE Mar 26 07:52:53 volumio volumio[1120]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Mar 26 07:52:53 volumio systemd[1]: vtcs.service: Killing process 2168 (vtcs) with signal SIGKILL. Mar 26 07:52:53 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 26 07:52:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 26 07:52:53 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:53 volumio sudo[2491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:53 volumio sudo[2491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:53 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:52:53 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:53 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio sudo[2508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:52:53 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 26 07:52:53 volumio sudo[2508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:53 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:53 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:53 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:53 volumio sudo[2508]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.933-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=805 volume= Mar 26 07:52:53 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:53.934-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:53 volumio volumio[1120]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 07:52:53 volumio volumio[1120]: info: MPD Permissions set Mar 26 07:52:53 volumio sudo[2491]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:53 volumio sudo[2511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:52:54 volumio sudo[2511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:54 volumio sudo[2487]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 07:52:54 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 07:52:54 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:54 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:54 volumio sudo[2512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:52:54 volumio sudo[2512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:54 volumio volumio[1120]: info: Software Volume ALSA configuration written Mar 26 07:52:54 volumio volumio[1120]: info: Preparing to generate the ALSA configuration file Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:54 volumio qobuz-connect[2134]: 20260326 07:52:54.230 [2134.2134] INFO SampleApp: Stopping Local configuration server Mar 26 07:52:54 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 07:52:54 volumio volumio[1120]: info: Getting Alsa Cards List without I2S DAC Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 07:52:54 volumio volumio[1120]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 26 07:52:54 volumio volumio[1120]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 07:52:54 volumio volumio[1120]: info: Reading ALSA contributions from plugins. Mar 26 07:52:54 volumio volumio[1120]: info: VolumeController::SetAlsaVolume0 Mar 26 07:52:54 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:54 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:54 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:54 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:54 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:54.584-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=805 volume=0 Mar 26 07:52:54 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:54.584-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:54 volumio volumio[1120]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 26 07:52:54 volumio volumio[1120]: info: Starting Shairport Sync Mar 26 07:52:54 volumio volumio[1120]: info: Asound.conf file written Mar 26 07:52:54 volumio sudo[2540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 07:52:54 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:54 volumio sudo[2543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 26 07:52:54 volumio sudo[2543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:54 volumio sudo[2543]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 07:52:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 07:52:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:52:54 volumio systemd[1]: shairport-sync.service: Consumed 2.000s CPU time. Mar 26 07:52:54 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 26 07:52:54 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 26 07:52:54 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 26 07:52:54 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:52:54 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:52:54 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:52:54 volumio volumio[1120]: Found hardware: "RPi-simple" "" "" "" "" Mar 26 07:52:54 volumio volumio[1120]: Hardware is initialized using a generic method Mar 26 07:52:54 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:52:54 volumio volumio[1120]: info: Output device has changed, restarting MPD Mar 26 07:52:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:52:54 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:55 volumio volumio[1120]: info: Output device has changed, restarting Shairport Sync Mar 26 07:52:55 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:55 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:55 volumio sudo[2554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 07:52:55 volumio sudo[2554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:55 volumio sudo[2552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 07:52:55 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:55 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:55 volumio volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 07:52:55 volumio volumio[1120]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 07:52:55 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:55 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:55 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:55 volumio volumio[1120]: info: Preparing to generate the ALSA configuration file Mar 26 07:52:55 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 07:52:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 07:52:55 volumio systemd[1]: mpd.service: Consumed 1.327s CPU time. Mar 26 07:52:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 07:52:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 07:52:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 07:52:55 volumio sudo[2584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 07:52:55 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 07:52:55 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:55 volumio qobuz-connect[2134]: 20260326 07:52:55.643 [2134.2134] INFO SampleApp: shat down connection on UNIX socket Mar 26 07:52:55 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 07:52:55 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 07:52:55 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 07:52:55 volumio sudo[2511]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:55 volumio sudo[2512]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:55 volumio sudo[2596]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 07:52:55 volumio sudo[2596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 07:52:55 volumio sudo[2596]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:56 volumio volumio[1120]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 26 07:52:56 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 26 07:52:56 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 26 07:52:56 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 26 07:52:56 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:52:56 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:52:56 volumio volumio[1120]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 07:52:56 volumio volumio[1120]: Found hardware: "RPi-simple" "" "" "" "" Mar 26 07:52:56 volumio volumio[1120]: Hardware is initialized using a generic method Mar 26 07:52:56 volumio volumio[1120]: No state is present for card sndrpihifiberry Mar 26 07:52:56 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:56 volumio volumio[1120]: info: Output device has changed, restarting MPD Mar 26 07:52:56 volumio volumio[1120]: info: Output device has changed, restarting Shairport Sync Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:56 volumio sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:56 volumio sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio sudo[2612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 07:52:56 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio sudo[2610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 07:52:56 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 07:52:56 volumio volumio[1120]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:56 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:56 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 26 07:52:56 volumio sudo[2624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:56 volumio volumio[1120]: info: Updating Volume Controller Parameters: Device: 3 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 26 07:52:56 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio sudo[2608]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 26 07:52:56 volumio volumio[1120]: info: Disabling external Volume Control Mar 26 07:52:56 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 07:52:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 07:52:56 volumio systemd[1]: mpd.service: Consumed 1.165s CPU time. Mar 26 07:52:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 07:52:56 volumio sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 07:52:56 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 07:52:56 volumio sudo[2641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:56 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:56 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio sudo[2651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:52:56 volumio sudo[2651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 07:52:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 07:52:56 volumio sudo[2658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:52:56 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio sudo[2666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:52:56 volumio volumio[1120]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 26 07:52:56 volumio volumio[1120]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 07:52:56 volumio volumio[1120]: info: Reading ALSA contributions from plugins. Mar 26 07:52:56 volumio sudo[2666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:56 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:56 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:56 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:56 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:56 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:57 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:57.017-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=805 volume=100 Mar 26 07:52:57 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:57.018-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:57 volumio volumio[1120]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 26 07:52:57 volumio volumio[1120]: info: MPD Permissions set Mar 26 07:52:57 volumio volumio[1120]: info: MPD Permissions set Mar 26 07:52:57 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:52:57 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:52:57 volumio volumio[1120]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 26 07:52:57 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:57 volumio volumio[1120]: info: Shairport-Sync Started Mar 26 07:52:57 volumio volumio[1120]: info: Executing endpoint qc_getconfig Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 26 07:52:57 volumio sudo[2666]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.097 [2601.2601] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 26 07:52:57 volumio sudo[2658]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:52:57 volumio sudo[2651]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.110 [2601.2601] INFO VolumeManager: [0x63da48]: Setting new playback volume: 75 Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.117 [2601.2601] INFO VolumeManager: [0x63da48]: Setting new mute state: 0 Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.117 [2601.2601] INFO AudioStreamManager: [0x63d7a0]: Setting new audio download buffer size: 1048576 Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.117 [2601.2601] INFO QobuzConnect: [0x63e310]: Client initialized! Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.117 [2601.2601] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 26 07:52:57 volumio volumio[1120]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 26 07:52:57 volumio volumio[1120]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 26 07:52:57 volumio volumio[1120]: info: TidalConnect service stoped! Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio sudo[2663]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 07:52:57 volumio sudo[2663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio sudo[2663]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.181 [2601.2601] INFO LocalConfigManager: [0x63d4c8]: Starting Local Configuration server Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.181 [2601.2601] INFO SampleApp: Starting Local configuration server Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.183 [2601.2601] INFO SampleApp: Connected to UNIX socket client 0x628818 Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio sudo[2672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:52:57 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio sudo[2674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:52:57 volumio sudo[2674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:52:57 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.247 [2601.2601] INFO SampleApp: Playback volume changed: 75 Mar 26 07:52:57 volumio sudo[2639]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:57 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio volumio[1120]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:52:57 volumio volumio[1120]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:52:57 volumio volumio[1120]: info: Starting Shairport Sync Mar 26 07:52:57 volumio volumio[1120]: info: Starting Shairport Sync Mar 26 07:52:57 volumio volumio[1120]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:52:57 volumio volumio[1120]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 26 07:52:57 volumio sudo[2712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 07:52:57 volumio sudo[2712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio volumio[1120]: info: Asound.conf file unchanged, so no further update is needed Mar 26 07:52:57 volumio volumio[1120]: info: Output device has changed, restarting MPD Mar 26 07:52:57 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 07:52:57 volumio qobuz-connect[2601]: 20260326 07:52:57.447 [2601.2601] INFO SampleApp: Stopping Local configuration server Mar 26 07:52:57 volumio sudo[2714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 07:52:57 volumio sudo[2714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio sudo[2717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 26 07:52:57 volumio volumio[1120]: info: Output device has changed, restarting Shairport Sync Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:57 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio sudo[2721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 07:52:57 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 07:52:57 volumio sudo[2723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 07:52:57 volumio sudo[2723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 07:52:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:52:57 volumio systemd[1]: shairport-sync.service: Consumed 1.574s CPU time. Mar 26 07:52:57 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 07:52:57 volumio volumio[1120]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: QobuzConnect: setDeactiveState invoked Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:57 volumio volumio[1120]: info: Preparing to generate the ALSA configuration file Mar 26 07:52:57 volumio sudo[2733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:57 volumio sudo[2733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio sudo[2736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 07:52:57 volumio sudo[2736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio volumio[1120]: info: VolumeController::SetAlsaVolume100 Mar 26 07:52:57 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 26 07:52:57 volumio sudo[2744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 07:52:57 volumio sudo[2744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 26 07:52:57 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 26 07:52:57 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 26 07:52:57 volumio sudo[2712]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio sudo[2714]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 26 07:52:57 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:57.777-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" state=STATUS_PLAYING positionMs=805 volume=100 Mar 26 07:52:57 volumio volumio5-onboarding[2111]: time=2026-03-26T07:52:57.786-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.103:37152 @ 0x2001c20" id=tidal://song/5274607 title="Just the Way You Are" Mar 26 07:52:57 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 07:52:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 07:52:57 volumio volumio[1120]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 26 07:52:57 volumio volumio[1120]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 07:52:57 volumio volumio[1120]: info: Reading ALSA contributions from plugins. Mar 26 07:52:57 volumio sudo[2752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 26 07:52:57 volumio sudo[2752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio volumio[1120]: info: MPD Permissions set Mar 26 07:52:57 volumio volumio[1120]: info: Shairport-Sync Started Mar 26 07:52:57 volumio volumio[1120]: info: Shairport-Sync Started Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio sudo[2744]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio sudo[2733]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 07:52:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 07:52:57 volumio sudo[2768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 07:52:57 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 07:52:57 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 07:52:58 volumio volumio[1120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 07:52:58 volumio volumio[1120]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Mar 26 07:52:58 volumio volumio[1120]: Job for vtcs.service canceled. Mar 26 07:52:58 volumio volumio[1120]: at ChildProcess.exithandler (node:child_process:421:12) Mar 26 07:52:58 volumio volumio[1120]: at ChildProcess.emit (node:events:514:28) Mar 26 07:52:58 volumio volumio[1120]: at maybeClose (node:internal/child_process:1105:16) Mar 26 07:52:58 volumio volumio[1120]: at Socket. (node:internal/child_process:457:11) Mar 26 07:52:58 volumio volumio[1120]: at Socket.emit (node:events:514:28) Mar 26 07:52:58 volumio volumio[1120]: at Pipe. (node:net:337:12) { Mar 26 07:52:58 volumio volumio[1120]: code: 1, Mar 26 07:52:58 volumio volumio[1120]: killed: false, Mar 26 07:52:58 volumio volumio[1120]: signal: null, Mar 26 07:52:58 volumio volumio[1120]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Mar 26 07:52:58 volumio volumio[1120]: stdout: '', Mar 26 07:52:58 volumio volumio[1120]: stderr: 'Job for vtcs.service canceled.\n' Mar 26 07:52:58 volumio volumio[1120]: } Mar 26 07:52:58 volumio volumio[1120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 07:52:58 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 07:52:58 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 07:52:58 volumio sudo[2752]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:58 volumio sudo[2736]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:58 volumio sudo[2779]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 07:52:58 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 07:52:58 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root Mar 26 07:52:59 volumio sudo[2794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 07:51' Mar 26 07:52:59 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 07:52:59 volumio qobuz-connect[2601]: 20260326 07:52:59.193 [2601.2601] INFO SampleApp: shat down connection on UNIX socket Mar 26 07:52:59 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 07:52:59 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"