Dec 13 22:46:01 volumio go-librespot[1674]: time="2025-12-13T22:46:01-06:00" level=trace msg="sent dealer ping" Dec 13 22:46:01 volumio volumio[1175]: info: [youtube2] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Dec 13 22:46:01 volumio volumio[1175]: info: [youtube2] Innertube support service: Start service with Node Dec 13 22:46:01 volumio go-librespot[1674]: time="2025-12-13T22:46:01-06:00" level=trace msg="received dealer pong" Dec 13 22:46:01 volumio volumio[1175]: SPOTIFY: SETTING SPOTIFY VOLUME 66 Dec 13 22:46:01 volumio volumio[1175]: info: Sending Spotify command with payload to local API: /player/volume Dec 13 22:46:01 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::volumioGetBrowseSources Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 22:46:02 volumio volumio[1175]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 13 22:46:02 volumio volumio[1175]: info: Completed starting MyVolumio Plugin Dec 13 22:46:02 volumio volumio[1175]: [Metrics] CommandRouter: 100s 804.77ms Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::volumiosetStartupVolume Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: VolumeController:: Setting startup Volume 40 Dec 13 22:46:02 volumio volumio[1175]: info: VolumeController::SetAlsaVolume40 Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::Close All Modals sent Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::Close All Modals sent Dec 13 22:46:02 volumio volumio[1175]: info: CoreStateMachine::pushState Dec 13 22:46:02 volumio volumio[1175]: info: CorePlayQueue::getTrack 0 Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 22:46:02 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Dec 13 22:46:03 volumio volumio[1175]: info: Setting Geolocation for MyVolumio to us3 Dec 13 22:46:03 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:03 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:03 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:03 volumio volumio[1175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Dec 13 22:46:03 volumio volumio[1175]: SPOTIFY: SPOTIFY VOLUME 66 Dec 13 22:46:03 volumio volumio[1175]: SPOTIFY: VOLUMIO VOLUME 40 Dec 13 22:46:03 volumio volumio[1175]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 13 22:46:03 volumio volumio[1175]: info: Setting Spotify Volume from Volumio: 40 Dec 13 22:46:03 volumio go-librespot[1674]: time="2025-12-13T22:46:03-06:00" level=debug msg="update volume requested to 43253/65535" Dec 13 22:46:03 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 13 22:46:03 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 22:46:03 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 13 22:46:03 volumio volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 13 22:46:03 volumio volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 13 22:46:03 volumio volumio[1175]: info: camilladsp stopping service pid 1598... Dec 13 22:46:03 volumio go-librespot[1674]: time="2025-12-13T22:46:03-06:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 13 22:46:03 volumio go-librespot[1674]: time="2025-12-13T22:46:03-06:00" level=trace msg="emitting websocket event: volume" Dec 13 22:46:04 volumio volumio[1175]: grep: /proc/1598/cmdline: binary file matches Dec 13 22:46:04 volumio volumio[1175]: grep: /proc/1598/cmdline: binary file matches Dec 13 22:46:04 volumio volumio[1175]: grep: /proc/1598/cmdline: binary file matches Dec 13 22:46:04 volumio volumio[1175]: grep: /proc/1598/cmdline: binary file matches Dec 13 22:46:04 volumio volumio[1175]: info: camilladsp service terminated, instance 1 Dec 13 22:46:04 volumio volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 13 22:46:04 volumio volumio[1175]: info: camilladsp service started and running in background, instance 1 Dec 13 22:46:04 volumio volumio[1175]: info: Setting Geolocation for MyVolumio to us3 Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:04 volumio volumio[1175]: info: Updating MyVolumio device info Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:04 volumio volumio[1175]: info: Remote config written successfully Dec 13 22:46:04 volumio volumio[1175]: info: Starting Tunnel 1 Dec 13 22:46:04 volumio volumio[1175]: info: Starting Tunnel Connection Checker Dec 13 22:46:04 volumio volumio[1175]: SPOTIFY: received: {"type":"volume","data":{"value":66,"max":100}} Dec 13 22:46:04 volumio volumio[1175]: SPOTIFY: RECEIVED SPOTIFY VOLUME 66 Dec 13 22:46:04 volumio volumio[1175]: info: Setting Volumio Volume from Spotify: 66 Dec 13 22:46:04 volumio volumio[1175]: info: VolumeController::SetAlsaVolume66 Dec 13 22:46:04 volumio volumio[1175]: info: CoreStateMachine::pushState Dec 13 22:46:04 volumio volumio[1175]: info: CorePlayQueue::getTrack 0 Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 22:46:04 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Dec 13 22:46:05 volumio volumio[1175]: info: MYVolumio Device enabled Dec 13 22:46:05 volumio volumio[1175]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 13 22:46:05 volumio volumio[1175]: info: MyVolumio status changed Dec 13 22:46:05 volumio volumio[1175]: info: Streaming services startup Dec 13 22:46:05 volumio volumio[1175]: info: Starting Streaming Daemon Dec 13 22:46:05 volumio volumio[1175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Dec 13 22:46:05 volumio sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 13 22:46:05 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:05 volumio volumio[1175]: SPOTIFY: SETTING SPOTIFY VOLUME 40 Dec 13 22:46:05 volumio volumio[1175]: info: Sending Spotify command with payload to local API: /player/volume Dec 13 22:46:05 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:05 volumio volumio[1175]: info: CorePlayQueue::getTrack 0 Dec 13 22:46:05 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:05 volumio volumio[1175]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 13 22:46:05 volumio go-librespot[1674]: time="2025-12-13T22:46:05-06:00" level=debug msg="update volume requested to 26214/65535" Dec 13 22:46:05 volumio volumio[1175]: info: Updating MyVolumio device info Dec 13 22:46:05 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:05 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:05 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:05 volumio volumio[1175]: info: Successfully Added MyVolumio device Dec 13 22:46:05 volumio go-librespot[1674]: time="2025-12-13T22:46:05-06:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 13 22:46:05 volumio go-librespot[1674]: time="2025-12-13T22:46:05-06:00" level=trace msg="emitting websocket event: volume" Dec 13 22:46:05 volumio volumio[1175]: error: Cannot start Volumio Streaming Daemon Dec 13 22:46:05 volumio volumio[1175]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 13 22:46:05 volumio volumio[1175]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 13 22:46:05 volumio volumio[1175]: SPOTIFY: received: {"type":"volume","data":{"value":40,"max":100}} Dec 13 22:46:05 volumio volumio[1175]: SPOTIFY: RECEIVED SPOTIFY VOLUME 40 Dec 13 22:46:05 volumio volumio[1175]: info: Setting Volumio Volume from Spotify: 40 Dec 13 22:46:05 volumio volumio[1175]: info: VolumeController::SetAlsaVolume40 Dec 13 22:46:06 volumio volumio[1175]: info: CoreStateMachine::pushState Dec 13 22:46:06 volumio volumio[1175]: info: CorePlayQueue::getTrack 0 Dec 13 22:46:06 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 22:46:06 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Dec 13 22:46:06 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 13 22:46:06 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 13 22:46:07 volumio volumio[1175]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 13 22:46:07 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 13 22:46:14 volumio volumio[1175]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 13 22:46:14 volumio sudo[2366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 13 22:46:14 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:14 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:14 volumio volumio[1175]: info: MRS: MultiRoom plugin initialized Dec 13 22:46:14 volumio volumio[1175]: info: MRS: STOPPING SNAPCLIENT Dec 13 22:46:14 volumio volumio[1175]: info: MRS: Snap server stop Dec 13 22:46:14 volumio volumio[1175]: info: MRS: STOPPING volumioStreaming Dec 13 22:46:14 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 13 22:46:14 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 13 22:46:14 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:14 volumio sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 13 22:46:14 volumio sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:14 volumio sudo[2389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 13 22:46:14 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:14 volumio sudo[2388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 13 22:46:14 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:14 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 13 22:46:14 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:14 volumio sudo[2384]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:14 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 13 22:46:14 volumio sudo[2387]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:14 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:14 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 13 22:46:15 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 13 22:46:15 volumio volumio[1175]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 13 22:46:15 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 13 22:46:15 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 13 22:46:15 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 13 22:46:15 volumio volumio[1175]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 13 22:46:15 volumio volumio[1175]: info: Preparing to generate the ALSA configuration file Dec 13 22:46:15 volumio volumio[1175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Dec 13 22:46:15 volumio volumio[1175]: info: BOOT COMPLETED Dec 13 22:46:15 volumio volumio[1175]: info: Listing playlists Dec 13 22:46:15 volumio volumio[1175]: info: Listing playlists Dec 13 22:46:15 volumio volumio[1175]: info: Successfully Updated MyVolumio device Dec 13 22:46:16 volumio volumio[1175]: info: Successfully Updated MyVolumio device Dec 13 22:46:16 volumio volumio[1175]: info: Successfully Added MyVolumio device Dec 13 22:46:16 volumio volumio[1175]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 13 22:46:16 volumio volumio[1175]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 13 22:46:16 volumio volumio[1175]: info: Reading ALSA contributions from plugins. Dec 13 22:46:16 volumio volumio[1175]: info: MRS: Removed streaming files Dec 13 22:46:16 volumio volumio[1175]: info: MRS: volumioStreaming STOPPED Dec 13 22:46:16 volumio volumio[1175]: info: MRS: SNAPSERVER STOPPED Dec 13 22:46:16 volumio volumio[1175]: info: MRS: SNAPCLIENT STOPPED Dec 13 22:46:18 volumio sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 13 22:46:18 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:18 volumio volumio[1175]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 13 22:46:18 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 13 22:46:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 13 22:46:18 volumio volumio[1175]: info: CorePlayQueue::getTrack 0 Dec 13 22:46:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 13 22:46:18 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:18 volumio autossh[2425]: port set to 0, monitoring disabled Dec 13 22:46:18 volumio autossh[2425]: starting ssh (count 1) Dec 13 22:46:18 volumio autossh[2425]: ssh child pid is 2428 Dec 13 22:46:18 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:18 volumio volumio[1175]: info: CorePlayQueue::getTrack 0 Dec 13 22:46:18 volumio volumio[1175]: info: Remote SSH Started Dec 13 22:46:18 volumio volumiossh-tunnel[2428]: Warning: Permanently added '[us3.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 13 22:46:19 volumio volumio[1175]: info: Asound.conf file written Dec 13 22:46:19 volumio sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 13 22:46:19 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:20 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:20 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 13 22:46:20 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 13 22:46:20 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 13 22:46:20 volumio volumio[1175]: info: Output device has changed, restarting MPD Dec 13 22:46:20 volumio volumio[1175]: info: Output device has changed, restarting Shairport Sync Dec 13 22:46:20 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:20 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:20 volumio sudo[2437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 22:46:20 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:20 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:20 volumio sudo[2440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 22:46:20 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:20 volumio volumio[1175]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 22:46:20 volumio volumio[1175]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 13 22:46:20 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:20 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 13 22:46:20 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 13 22:46:20 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 13 22:46:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 22:46:20 volumio systemd[1]: mpd.service: Consumed 1.666s CPU time. Dec 13 22:46:20 volumio volumio[1175]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 13 22:46:20 volumio volumio[1175]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 13 22:46:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 13 22:46:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 22:46:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 22:46:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 22:46:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 22:46:20 volumio volumio[1175]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 13 22:46:20 volumio volumio[1175]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 13 22:46:20 volumio volumio[1175]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 13 22:46:20 volumio sudo[2449]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 22:46:20 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 22:46:20 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:20 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 13 22:46:20 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 13 22:46:21 volumio volumio[1175]: info: MRS: Pushing multiroomSync output for this device Dec 13 22:46:21 volumio volumio[1175]: info: MRS: Pushing multiroomSync output Dec 13 22:46:21 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:21 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:21 volumio bluetoothd[834]: Path / reserved for Adv Monitor app :1.25 Dec 13 22:46:21 volumio volumio[1175]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 13 22:46:21 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:21 volumio bluetoothd[834]: Adv Monitor app :1.25 disconnected from D-Bus Dec 13 22:46:21 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 13 22:46:21 volumio volumio[1175]: info: Adding METAVOLUMIO REST API Endpoints Dec 13 22:46:21 volumio volumio[1175]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 13 22:46:21 volumio volumio[1175]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 13 22:46:21 volumio volumio[1175]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 13 22:46:21 volumio volumio[1175]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 13 22:46:21 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 13 22:46:21 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 13 22:46:21 volumio volumio[1175]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Dec 13 22:46:21 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 13 22:46:21 volumio volumio[1175]: info: Adding inputs REST Endpoints Dec 13 22:46:21 volumio volumio[1175]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 13 22:46:21 volumio volumio[1175]: info: Scanning Audio Inputs Dec 13 22:46:21 volumio volumio[1175]: info: Checking against Known Cards name Dec 13 22:46:21 volumio volumio[1175]: info: Adding Server instance for streaming Dec 13 22:46:21 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 13 22:46:22 volumio volumio[1175]: error: Hi Res Audio Failed Login: Missing Login Data Dec 13 22:46:22 volumio volumio[1175]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 13 22:46:22 volumio volumio[1175]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 13 22:46:22 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 13 22:46:22 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 13 22:46:22 volumio volumio[1175]: info: Refreshing QOBUZ token Dec 13 22:46:22 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 13 22:46:22 volumio volumio[1175]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Dec 13 22:46:22 volumio volumio[1175]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 13 22:46:22 volumio volumio[1175]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 13 22:46:22 volumio volumio[1175]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 13 22:46:22 volumio mpd[2451]: 2025-12-13T22:46:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 22:46:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 22:46:22 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:22 volumio sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 13 22:46:22 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:22 volumio volumio[1175]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 13 22:46:22 volumio volumio[1175]: info: Adding TIDAL REST API Endpoints Dec 13 22:46:22 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:22 volumio volumio[1175]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 13 22:46:22 volumio sudo[2469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 13 22:46:22 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 13 22:46:23 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:23 volumio volumio[1175]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 13 22:46:23 volumio volumio[1175]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 13 22:46:23 volumio volumio[1175]: info: MPD Permissions set Dec 13 22:46:23 volumio sudo[2473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 13 22:46:23 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:23 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 13 22:46:23 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:23 volumio volumiobt[2477]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 13 22:46:23 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 13 22:46:23 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:23 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:23 volumio sudo[2480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 13 22:46:23 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:23 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:23 volumio volumiobt[2482]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 13 22:46:23 volumio bluetoothd[834]: Path / reserved for Adv Monitor app :1.27 Dec 13 22:46:23 volumio bluetoothd[834]: Adv Monitor app :1.27 disconnected from D-Bus Dec 13 22:46:23 volumio volumiobt[2485]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 13 22:46:23 volumio volumiobt[2486]: [176B blob data] Dec 13 22:46:23 volumio volumiobt[2486]: [157B blob data] Dec 13 22:46:23 volumio volumiobt[2486]: [157B blob data] Dec 13 22:46:23 volumio volumiobt[2486]: [157B blob data] Dec 13 22:46:23 volumio volumiobt[2486]: [113B blob data] Dec 13 22:46:23 volumio volumiobt[2486]: [bluetoothctl]> discoverable on Dec 13 22:46:23 volumio volumiobt[2486]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 13 22:46:23 volumio volumiobt[2486]: [bluetoothctl]> pairable on Dec 13 22:46:23 volumio bluetoothd[834]: Path / reserved for Adv Monitor app :1.28 Dec 13 22:46:23 volumio bluetoothd[834]: Adv Monitor app :1.28 disconnected from D-Bus Dec 13 22:46:23 volumio volumiobt[2486]: [bluetoothctl]> Dec 13 22:46:23 volumio volumiobt[2487]: INFO [BTSTART] Registering Bluetooth agent... Dec 13 22:46:23 volumio volumiobt[2488]: [NEW] Media /org/bluez/hci0 Dec 13 22:46:23 volumio volumiobt[2488]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 13 22:46:23 volumio volumiobt[2488]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 13 22:46:23 volumio volumiobt[2488]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 13 22:46:24 volumio volumio[1175]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 13 22:46:24 volumio bluetoothd[834]: Adv Monitor app :1.29 disconnected from D-Bus Dec 13 22:46:24 volumio volumiobt[2489]: No agent is registered Dec 13 22:46:24 volumio volumiobt[2489]: [NEW] Media /org/bluez/hci0 Dec 13 22:46:24 volumio volumiobt[2489]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 13 22:46:24 volumio volumiobt[2489]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 13 22:46:24 volumio volumiobt[2489]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 13 22:46:24 volumio bluetoothd[834]: Adv Monitor app :1.30 disconnected from D-Bus Dec 13 22:46:24 volumio volumiobt[2490]: INFO [BTSTART] Agent registered successfully. Dec 13 22:46:24 volumio volumiobt[2491]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 13 22:46:24 volumio volumiobt[2492]: INFO:a2dp-agent:Connecting to system bus. Dec 13 22:46:24 volumio volumiobt[2492]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 13 22:46:24 volumio volumiobt[2492]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 13 22:46:24 volumio volumiobt[2492]: INFO:a2dp-agent:Registering agent. Dec 13 22:46:24 volumio volumiobt[2492]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 13 22:46:24 volumio volumiobt[2492]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 13 22:46:24 volumio volumio[1175]: info: Executing endpoint qc_getconfig Dec 13 22:46:24 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.096 [2471.2471] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 13 22:46:25 volumio volumio[1175]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::volumioStop Dec 13 22:46:25 volumio volumio[1175]: info: CoreStateMachine::stop Dec 13 22:46:25 volumio volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 22:46:25 volumio volumio[1175]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 13 22:46:25 volumio volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.196 [2471.2471] INFO VolumeManager: [0x13285a8]: Setting new playback volume: 75 Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.196 [2471.2471] INFO VolumeManager: [0x13285a8]: Setting new mute state: 0 Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.196 [2471.2471] INFO QobuzConnect: [0x1328f78]: Client initialized! Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.196 [2471.2471] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.208 [2471.2471] INFO LocalConfigManager: [0x1327e88]: Starting Local Configuration server Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.208 [2471.2471] INFO SampleApp: Starting Local configuration server Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.210 [2471.2471] INFO SampleApp: Connected to UNIX socket client 0x1312818 Dec 13 22:46:25 volumio qobuz-connect[2471]: 20251213 22:46:25.246 [2471.2471] INFO SampleApp: Playback volume changed: 75 Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:25 volumio volumio[1175]: info: Access Token successfully retrieved Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 22:46:25 volumio volumio[1175]: info: [1765687585645] CoreMusicLibrary::Adding element QOBUZ Dec 13 22:46:25 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source Calm Radio Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source Jellyfin Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source YouTube2 Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source YouTube Music Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source Podcast Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source Spotify Dec 13 22:46:25 volumio volumio[1175]: Cannot find translation for source QOBUZ Dec 13 22:46:26 volumio volumio[1175]: info: Stopping AccessToken refresher cron for QOBUZ Dec 13 22:46:26 volumio volumio[1175]: info: AccessToken refresher cron started for QOBUZ Dec 13 22:46:26 volumio volumio[1175]: info: Adding QOBUZ REST API Endpoints Dec 13 22:46:26 volumio volumio[1175]: error: updateQueue error: null Dec 13 22:46:26 volumio volumio[1175]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 13 22:46:26 volumio volumio[1175]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 13 22:46:26 volumio volumio[1175]: info: MRS: Getting audio outputs on start Dec 13 22:46:26 volumio volumio[1175]: info: MRS: Requesting all other devices output Dec 13 22:46:27 volumio sudo[2506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 22:46:27 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:27 volumio volumio[1175]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Dec 13 22:46:27 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:27 volumio volumio[1175]: info: MRS: Found cast device: WiiM-Ultra-2ae5b0d6d47e6d628aee0d3c3fdb603a Dec 13 22:46:27 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:27 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 13 22:46:27 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 13 22:46:27 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 13 22:46:27 volumio volumio[1175]: info: Starting Shairport Sync Dec 13 22:46:27 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 22:46:27 volumio mpd_monitor.sh[2510]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 22:46:27 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:27 volumio volumio[1175]: info: Preparing to generate the ALSA configuration file Dec 13 22:46:27 volumio sudo[2514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 22:46:27 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:27 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 22:46:27 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 22:46:27 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 22:46:27 volumio systemd[1]: shairport-sync.service: Consumed 1.660s CPU time. Dec 13 22:46:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 22:46:27 volumio sudo[2514]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:28 volumio volumio[1175]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 13 22:46:28 volumio volumio[1175]: info: Reading ALSA contributions from plugins. Dec 13 22:46:28 volumio volumio[1175]: info: MRS: Found cast device: MagniFi-Max-f2262d901cf1001a72956fd4538f8fc8 Dec 13 22:46:28 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:28 volumio volumio[1175]: info: Shairport-Sync Started Dec 13 22:46:28 volumio volumio[1175]: info: Successfully started MPD Monitor Dec 13 22:46:28 volumio volumio[1175]: info: MRS: Found cast device: SHIELD-Android-TV-f6a24771a38231cc948c22ae6a87deeb Dec 13 22:46:28 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:28 volumio volumio[1175]: info: MRS: Found cast device: P65-E1-2e2d454309f48b86d9866d7a1ad4ee32 Dec 13 22:46:28 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:28 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:29 volumio volumio[1175]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.yOHd4X4G7heVbPl0bTta2YvHlHJ3.4aa0ed4e80c048a721e4111055bdb46c.state.status' Dec 13 22:46:29 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:29 volumio volumio[1175]: info: Listing playlists Dec 13 22:46:29 volumio volumio[1175]: info: Listing playlists Dec 13 22:46:29 volumio volumio[1175]: info: MRS: Found cast device: M65-D0-31496775968dcc7744e8e364c57ccaae Dec 13 22:46:29 volumio volumio[1175]: info: Adding audio output: Dec 13 22:46:29 volumio volumio[1175]: info: Asound.conf file written Dec 13 22:46:29 volumio sudo[2549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 13 22:46:29 volumio sudo[2549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:29 volumio sudo[2549]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:29 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 13 22:46:29 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 13 22:46:29 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 13 22:46:29 volumio volumio[1175]: info: Output device has changed, restarting MPD Dec 13 22:46:29 volumio volumio[1175]: info: Output device has changed, restarting Shairport Sync Dec 13 22:46:29 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:29 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:29 volumio sudo[2555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 22:46:29 volumio sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 22:46:29 volumio sudo[2555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:29 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:29 volumio sudo[2555]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:30 volumio volumio[1175]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 22:46:30 volumio volumio[1175]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 13 22:46:30 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:30 volumio volumio[1175]: info: QobuzConnect: setDeactiveState invoked Dec 13 22:46:30 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:30 volumio volumio[1175]: info: Preparing to generate the ALSA configuration file Dec 13 22:46:30 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 13 22:46:30 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 13 22:46:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 22:46:30 volumio systemd[1]: mpd.service: Consumed 1.670s CPU time. Dec 13 22:46:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 13 22:46:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 22:46:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 22:46:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 22:46:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 22:46:30 volumio sudo[2568]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 22:46:30 volumio sudo[2568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 22:46:30 volumio sudo[2568]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:30 volumio volumio[1175]: info: [youtube2] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":42897}} Dec 13 22:46:30 volumio volumio[1175]: info: [youtube2] Innertube support service running at http://127.0.0.1:42897 Dec 13 22:46:30 volumio sudo[2575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 13 22:46:30 volumio sudo[2575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:30 volumio volumio[1175]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 13 22:46:30 volumio volumio[1175]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 13 22:46:30 volumio volumio[1175]: info: Reading ALSA contributions from plugins. Dec 13 22:46:31 volumio volumio[1175]: info: MPD Permissions set Dec 13 22:46:31 volumio sudo[2575]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio sudo[2577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 13 22:46:31 volumio sudo[2577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:31 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:31 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 13 22:46:31 volumio qobuz-connect[2471]: 20251213 22:46:31.670 [2471.2471] INFO SampleApp: Stopping Local configuration server Dec 13 22:46:31 volumio go-librespot[1674]: time="2025-12-13T22:46:31-06:00" level=trace msg="sent dealer ping" Dec 13 22:46:31 volumio go-librespot[1674]: time="2025-12-13T22:46:31-06:00" level=trace msg="received dealer pong" Dec 13 22:46:31 volumio volumio[1175]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 13 22:46:32 volumio mpd[2573]: 2025-12-13T22:46:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 22:46:32 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 22:46:32 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:32 volumio volumio[1175]: info: Starting Shairport Sync Dec 13 22:46:32 volumio sudo[2587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 22:46:32 volumio sudo[2587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 22:46:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 22:46:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 22:46:32 volumio systemd[1]: shairport-sync.service: Consumed 1.304s CPU time. Dec 13 22:46:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 22:46:32 volumio sudo[2587]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 22:46:32 volumio volumio[1175]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 13 22:46:32 volumio volumio[1175]: info: Listing playlists Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:32 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 22:46:32 volumio volumio[1175]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 13 22:46:32 volumio volumio[1175]: info: Listing playlists Dec 13 22:46:32 volumio volumio[1175]: error: updateQueue error: null Dec 13 22:46:32 volumio volumio[1175]: info: Shairport-Sync Started Dec 13 22:46:33 volumio volumio[1175]: info: Asound.conf file written Dec 13 22:46:33 volumio qobuz-connect[2471]: 20251213 22:46:33.225 [2471.2471] INFO SampleApp: shat down connection on UNIX socket Dec 13 22:46:33 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 13 22:46:33 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 13 22:46:33 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 13 22:46:33 volumio sudo[2577]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:33 volumio sudo[2607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 13 22:46:33 volumio sudo[2607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:33 volumio sudo[2607]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:33 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 13 22:46:33 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 13 22:46:33 volumio volumio[1175]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 13 22:46:33 volumio volumio[1175]: info: Output device has changed, restarting MPD Dec 13 22:46:33 volumio volumio[1175]: info: Output device has changed, restarting Shairport Sync Dec 13 22:46:33 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:33 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:34 volumio sudo[2615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 22:46:34 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:34 volumio volumio[1175]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 22:46:34 volumio volumio[1175]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 13 22:46:34 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:34 volumio sudo[2617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 22:46:34 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:34 volumio sudo[2615]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:34 volumio volumio[1175]: info: QobuzConnect: setDeactiveState invoked Dec 13 22:46:34 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 13 22:46:34 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 13 22:46:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 22:46:34 volumio systemd[1]: mpd.service: Consumed 1.685s CPU time. Dec 13 22:46:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 13 22:46:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 22:46:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 22:46:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 22:46:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 22:46:34 volumio sudo[2629]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 22:46:34 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 22:46:34 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:34 volumio volumio[1175]: info: MPD Permissions set Dec 13 22:46:34 volumio volumio[1175]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 13 22:46:34 volumio volumio[1175]: info: QobuzConnect: setDeactiveState invoked Dec 13 22:46:34 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:34 volumio sudo[2634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 13 22:46:34 volumio volumio[1175]: info: Executing endpoint qc_getconfig Dec 13 22:46:34 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 13 22:46:34 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:34 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.008 [2608.2608] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.012 [2608.2608] INFO VolumeManager: [0x99c5a8]: Setting new playback volume: 75 Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.012 [2608.2608] INFO VolumeManager: [0x99c5a8]: Setting new mute state: 0 Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.012 [2608.2608] INFO QobuzConnect: [0x99cf78]: Client initialized! Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.012 [2608.2608] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.032 [2608.2608] INFO LocalConfigManager: [0x99be88]: Starting Local Configuration server Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.032 [2608.2608] INFO SampleApp: Starting Local configuration server Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.033 [2608.2608] INFO SampleApp: Connected to UNIX socket client 0x986818 Dec 13 22:46:35 volumio sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 22:46:35 volumio volumio[1175]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 13 22:46:35 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.158 [2608.2608] INFO SampleApp: Playback volume changed: 75 Dec 13 22:46:35 volumio volumio[1175]: info: Received Get System Version Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 22:46:35 volumio volumio[1175]: info: Received Get System Info Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 22:46:35 volumio volumio[1175]: info: Discovery: Getting this device information Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 22:46:35 volumio volumio[1175]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 13 22:46:35 volumio volumio[1175]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 13 22:46:35 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 13 22:46:35 volumio qobuz-connect[2608]: 20251213 22:46:35.305 [2608.2608] INFO SampleApp: Stopping Local configuration server Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 22:46:35 volumio sudo[2644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 22:46:35 volumio sudo[2644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:35 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:36 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 13 22:46:36 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 13 22:46:36 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 13 22:46:36 volumio volumio[1175]: info: Starting Shairport Sync Dec 13 22:46:36 volumio mpd[2632]: 2025-12-13T22:46:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 22:46:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 22:46:36 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:36 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 22:46:36 volumio mpd_monitor.sh[2655]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 22:46:36 volumio sudo[2644]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:36 volumio volumio[1175]: info: Successfully started MPD Monitor Dec 13 22:46:36 volumio volumio[1175]: error: updateQueue error: null Dec 13 22:46:36 volumio sudo[2652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 22:46:36 volumio sudo[2652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:46:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 22:46:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 22:46:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 22:46:36 volumio systemd[1]: shairport-sync.service: Consumed 1.365s CPU time. Dec 13 22:46:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 22:46:36 volumio sudo[2652]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:36 volumio volumio[1175]: info: Shairport-Sync Started Dec 13 22:46:37 volumio qobuz-connect[2608]: 20251213 22:46:37.045 [2608.2608] INFO SampleApp: shat down connection on UNIX socket Dec 13 22:46:37 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 13 22:46:37 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 13 22:46:37 volumio volumio[1175]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 13 22:46:37 volumio volumio[1175]: info: QobuzConnect: setDeactiveState invoked Dec 13 22:46:37 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:37 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 13 22:46:37 volumio sudo[2639]: pam_unix(sudo:session): session closed for user root Dec 13 22:46:37 volumio volumio[1175]: info: Executing endpoint qc_getconfig Dec 13 22:46:37 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.350 [2675.2675] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.354 [2675.2675] INFO VolumeManager: [0xa935a8]: Setting new playback volume: 75 Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.355 [2675.2675] INFO VolumeManager: [0xa935a8]: Setting new mute state: 0 Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.355 [2675.2675] INFO QobuzConnect: [0xa93f78]: Client initialized! Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.355 [2675.2675] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.376 [2675.2675] INFO LocalConfigManager: [0xa92e88]: Starting Local Configuration server Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.376 [2675.2675] INFO SampleApp: Starting Local configuration server Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.376 [2675.2675] INFO SampleApp: Connected to UNIX socket client 0xa7d818 Dec 13 22:46:37 volumio volumio[1175]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 13 22:46:37 volumio volumio[1175]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 13 22:46:37 volumio qobuz-connect[2675]: 20251213 22:46:37.501 [2675.2675] INFO SampleApp: Playback volume changed: 75 Dec 13 22:46:37 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:46:38 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Dec 13 22:47:01 volumio go-librespot[1674]: time="2025-12-13T22:47:01-06:00" level=trace msg="received accesspoint ping" Dec 13 22:47:01 volumio go-librespot[1674]: time="2025-12-13T22:47:01-06:00" level=trace msg="received accesspoint pong ack" Dec 13 22:47:01 volumio go-librespot[1674]: time="2025-12-13T22:47:01-06:00" level=trace msg="sent dealer ping" Dec 13 22:47:01 volumio go-librespot[1674]: time="2025-12-13T22:47:01-06:00" level=trace msg="received dealer pong" Dec 13 22:47:06 volumio sudo[2730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 22:47:06 volumio sudo[2730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 22:47:06 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 13 22:47:06 volumio volumio[1175]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 13 22:47:06 volumio volumio[1175]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 22:47:06 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 13 22:47:06 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 13 22:47:06 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 13 22:47:06 volumio volumio[1175]: TypeError: Cannot read properties of undefined (reading 'then') Dec 13 22:47:06 volumio volumio[1175]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 13 22:47:06 volumio volumio[1175]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 13 22:47:06 volumio volumio[1175]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 13 22:47:06 volumio volumio[1175]: at Socket.emit (node:events:514:28) Dec 13 22:47:06 volumio volumio[1175]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 13 22:47:06 volumio volumio[1175]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 13 22:47:06 volumio volumio[1175]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 22:47:06 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 22:47:06 volumio mpd_monitor.sh[2732]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 22:47:06 volumio sudo[2730]: pam_unix(sudo:session): session closed for user root Dec 13 22:47:10 volumio sudo[2765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 22:46' Dec 13 22:47:10 volumio sudo[2765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"