Feb 11 22:41:00 volumio-black sudo[5908]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 22:41:00 volumio-black sudo[5908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:00 volumio-black sudo[5910]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 22:41:00 volumio-black sudo[5908]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:00 volumio-black sudo[5910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:00 volumio-black sudo[5910]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:00 volumio-black volumio[1218]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 16; SM-S721B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 22:41:00 volumio-black volumio[1218]: info: Received Get System Info Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:41:00 volumio-black volumio[1218]: info: Discovery: Getting this device information Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:00 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:00 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:00 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 22:41:00 volumio-black volumio[1218]: info: Received Get System Info Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:41:00 volumio-black volumio[1218]: info: Discovery: Getting this device information Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:00 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:00 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:00 volumio-black volumio[1218]: info: Listing playlists Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetQueue Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreStateMachine::getQueue Feb 11 22:41:00 volumio-black volumio[1218]: info: CorePlayQueue::getQueue Feb 11 22:41:00 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 22:41:02 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 22:41:02 volumio-black volumio[1218]: info: Received Get System Info Feb 11 22:41:02 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:41:02 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:41:02 volumio-black volumio[1218]: info: Discovery: Getting this device information Feb 11 22:41:02 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:02 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:02 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:41:05 volumio-black sudo[5914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 22:41:05 volumio-black sudo[5914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:05 volumio-black sudo[5916]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 22:41:05 volumio-black sudo[5916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:05 volumio-black sudo[5914]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:05 volumio-black sudo[5916]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:06 volumio-black volumio[1218]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 16; SM-S721B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 22:41:06 volumio-black volumio[1218]: info: Received Get System Info Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:41:06 volumio-black volumio[1218]: info: Discovery: Getting this device information Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 22:41:06 volumio-black volumio[1218]: info: Received Get System Info Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:41:06 volumio-black volumio[1218]: info: Discovery: Getting this device information Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: Listing playlists Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetQueue Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreStateMachine::getQueue Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getQueue Feb 11 22:41:06 volumio-black volumio[1218]: info: Received Get System Info Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:41:06 volumio-black volumio[1218]: info: Discovery: Getting this device information Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:06 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:06 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 22:41:07 volumio-black kernel: hwmon hwmon1: Undervoltage detected! Feb 11 22:41:08 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 11 22:41:08 volumio-black volumio[1218]: info: MyVolumio login type: Token Feb 11 22:41:08 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 22:41:08 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:08.582Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" userId= Feb 11 22:41:10 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 22:41:10 volumio-black volumio[1218]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 11 22:41:10 volumio-black volumio[1218]: info: MyVolumio token set successfully Feb 11 22:41:10 volumio-black volumio[1218]: info: MYVOLUMIO: Adding device Feb 11 22:41:10 volumio-black volumio[1218]: info: MYVOLUMIO: Evaluating Server Feb 11 22:41:10 volumio-black volumio[1218]: info: MyVolumio Plan changed: premium Feb 11 22:41:10 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 11 22:41:10 volumio-black volumio[1218]: info: Removing browser output: myVolumio user plan is not superstar Feb 11 22:41:10 volumio-black volumio[1218]: info: Removing audio output: Feb 11 22:41:10 volumio-black volumio[1218]: info: MYVOLUMIO: Adding device Feb 11 22:41:10 volumio-black volumio[1218]: info: MYVOLUMIO: Evaluating Server Feb 11 22:41:10 volumio-black volumio[1218]: info: Remote config written successfully Feb 11 22:41:10 volumio-black volumio[1218]: info: Starting Tunnel 1 Feb 11 22:41:10 volumio-black volumio[1218]: info: Starting Tunnel Connection Checker Feb 11 22:41:11 volumio-black volumio[1218]: info: MYVolumio Device enabled Feb 11 22:41:11 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 11 22:41:11 volumio-black volumio[1218]: info: MyVolumio status changed Feb 11 22:41:11 volumio-black volumio[1218]: info: Streaming services startup Feb 11 22:41:11 volumio-black volumio[1218]: info: Starting Streaming Daemon Feb 11 22:41:11 volumio-black volumio[1218]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Feb 11 22:41:11 volumio-black sudo[5974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 22:41:11 volumio-black sudo[5974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:11 volumio-black sudo[5974]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:11 volumio-black volumio[1218]: error: Cannot start Volumio Streaming Daemon Feb 11 22:41:11 volumio-black volumio[1218]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 22:41:11 volumio-black volumio[1218]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 22:41:11 volumio-black kernel: hwmon hwmon1: Voltage normalised Feb 11 22:41:12 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 11 22:41:12 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 11 22:41:12 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 11 22:41:14 volumio-black sudo[5980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 11 22:41:14 volumio-black sudo[5980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:14 volumio-black sudo[5980]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:14 volumio-black volumio[1218]: info: MRS: MultiRoom plugin initialized Feb 11 22:41:14 volumio-black volumio[1218]: info: MRS: STOPPING SNAPCLIENT Feb 11 22:41:14 volumio-black volumio[1218]: info: MRS: Snap server stop Feb 11 22:41:14 volumio-black volumio[1218]: info: MRS: STOPPING volumioStreaming Feb 11 22:41:14 volumio-black sudo[5999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 11 22:41:14 volumio-black sudo[5999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:14 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 11 22:41:14 volumio-black sudo[6006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 11 22:41:14 volumio-black sudo[6006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:14 volumio-black sudo[6001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 11 22:41:14 volumio-black sudo[6006]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:14 volumio-black sudo[6001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:14 volumio-black sudo[6003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 11 22:41:14 volumio-black sudo[6003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:14 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 11 22:41:14 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 11 22:41:14 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 11 22:41:14 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 11 22:41:14 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 11 22:41:15 volumio-black sudo[5999]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:15 volumio-black sudo[6001]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:15 volumio-black sudo[6003]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 11 22:41:15 volumio-black volumio[1218]: info: Preparing to generate the ALSA configuration file Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: Removed streaming files Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: volumioStreaming STOPPED Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: SNAPSERVER STOPPED Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: SNAPCLIENT STOPPED Feb 11 22:41:15 volumio-black volumio[1218]: info: Asound.conf file unchanged, so no further update is needed Feb 11 22:41:15 volumio-black volumio[1218]: info: Output device has changed, restarting MPD Feb 11 22:41:15 volumio-black volumio[1218]: info: Output device has changed, restarting Shairport Sync Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:15 volumio-black sudo[6011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 22:41:15 volumio-black sudo[6011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:15 volumio-black sudo[6011]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:15 volumio-black sudo[6013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 22:41:15 volumio-black sudo[6013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:15 volumio-black volumio[1218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 11 22:41:15 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 11 22:41:15 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 11 22:41:15 volumio-black systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 22:41:15 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 11 22:41:15 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Feb 11 22:41:15 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output for this device Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding audio output: Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding audio output: Feb 11 22:41:15 volumio-black volumio[1218]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding METAVOLUMIO REST API Endpoints Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 11 22:41:15 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 11 22:41:15 volumio-black volumio[1218]: info: Preparing CD Folders Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding CD REST API Endpoints Feb 11 22:41:15 volumio-black volumio[1218]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 11 22:41:15 volumio-black volumio[1218]: info: Starting UDEV Watcher for CD Feb 11 22:41:15 volumio-black volumio[1218]: info: Detecting CD presence with UDEV Feb 11 22:41:15 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 11 22:41:15 volumio-black bluetoothd[723]: Path / reserved for Adv Monitor app :1.27 Feb 11 22:41:15 volumio-black bluetoothd[723]: Adv Monitor app :1.27 disconnected from D-Bus Feb 11 22:41:15 volumio-black systemd[1]: mpd.service: Deactivated successfully. Feb 11 22:41:15 volumio-black systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 22:41:15 volumio-black systemd[1]: mpd.service: Consumed 5.477s CPU time. Feb 11 22:41:15 volumio-black systemd[1]: mpd.socket: Deactivated successfully. Feb 11 22:41:15 volumio-black systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 22:41:15 volumio-black systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 22:41:15 volumio-black systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 22:41:15 volumio-black systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 22:41:16 volumio-black sudo[6036]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 22:41:16 volumio-black sudo[6036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 22:41:16 volumio-black sudo[6036]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:18 volumio-black mpd[6038]: 2026-02-11T22:41:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 22:41:18 volumio-black systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 22:41:18 volumio-black sudo[6013]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black volumio[1218]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 11 22:41:20 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding inputs REST Endpoints Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 11 22:41:20 volumio-black volumio[1218]: info: Scanning Audio Inputs Feb 11 22:41:20 volumio-black volumio[1218]: info: Checking against Known Cards name Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 22:41:20 volumio-black volumio[1218]: info: [1770849680497] CoreMusicLibrary::Adding element USB Audio CODEC Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 22:41:20 volumio-black volumio[1218]: Cannot find translation for source USB Audio CODEC Feb 11 22:41:20 volumio-black volumio[1218]: info: Checking against Known Cards name Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding Server instance for streaming Feb 11 22:41:20 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 11 22:41:20 volumio-black volumio[1218]: error: Hi Res Audio Failed Login: Missing Login Data Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 11 22:41:20 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 11 22:41:20 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 11 22:41:20 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 11 22:41:20 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 11 22:41:20 volumio-black volumio[1218]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 11 22:41:20 volumio-black sudo[6062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 22:41:20 volumio-black sudo[6062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:20 volumio-black sudo[6068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 22:41:20 volumio-black sudo[6068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:20 volumio-black volumio[1218]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding TIDAL REST API Endpoints Feb 11 22:41:20 volumio-black volumio[1218]: info: Stopping AccessToken refresher cron for QOBUZ Feb 11 22:41:20 volumio-black sudo[6068]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black sudo[6062]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black volumio[1218]: info: AccessToken refresher cron started for QOBUZ Feb 11 22:41:20 volumio-black volumio[1218]: info: Adding QOBUZ REST API Endpoints Feb 11 22:41:20 volumio-black volumio[1218]: info: MRS: Getting audio outputs on start Feb 11 22:41:20 volumio-black volumio[1218]: info: MRS: Requesting all other devices output Feb 11 22:41:20 volumio-black sudo[6070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 22:41:20 volumio-black sudo[6070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:20 volumio-black volumio[1218]: info: Retrieving Cloud Streaming UI Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 22:41:20 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Feb 11 22:41:20 volumio-black volumio[1218]: info: MPD Permissions set Feb 11 22:41:20 volumio-black systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 22:41:20 volumio-black sudo[6076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 11 22:41:20 volumio-black sudo[6070]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black sudo[6076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:20 volumio-black systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 11 22:41:20 volumio-black sudo[6076]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black volumiobt[6088]: INFO [BTSTART] Ensuring Bluetooth directory exists... Feb 11 22:41:20 volumio-black volumio[1218]: error: updateQueue error: null Feb 11 22:41:20 volumio-black volumio[1218]: info: Getting Tidal Cloud Configuration Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:20 volumio-black volumio[1218]: info: Getting Qobuz Cloud Configuration Feb 11 22:41:20 volumio-black sudo[6091]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Feb 11 22:41:20 volumio-black sudo[6091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:20 volumio-black volumio[1218]: info: Asking plugin for UI Config Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:20 volumio-black sudo[6091]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black volumio[1218]: info: Getting Spotify Cloud Configuration Feb 11 22:41:20 volumio-black volumio[1218]: info: Asking plugin for UI Config Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:20 volumio-black volumio[1218]: info: Saving Spotify Acccount Feb 11 22:41:20 volumio-black volumio[1218]: info: Got it Feb 11 22:41:20 volumio-black volumio[1218]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 22:41:20 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreCommandRouter::volumioStop Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreStateMachine::stop Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:41:20 volumio-black volumio[1218]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Feb 11 22:41:20 volumio-black volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:41:20 volumio-black sudo[6094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Feb 11 22:41:20 volumio-black sudo[6094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:20 volumio-black sudo[6094]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:20 volumio-black volumiobt[6096]: INFO [BTSTART] Powering on Bluetooth if needed... Feb 11 22:41:21 volumio-black bluetoothd[723]: Adv Monitor app :1.28 disconnected from D-Bus Feb 11 22:41:21 volumio-black volumiobt[6099]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Feb 11 22:41:21 volumio-black volumio[1218]: info: Executing endpoint qc_getconfig Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.095 [6074.6074] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 22:41:21 volumio-black volumio[1218]: info: Starting Shairport Sync Feb 11 22:41:21 volumio-black volumiobt[6101]: [176B blob data] Feb 11 22:41:21 volumio-black volumiobt[6101]: [157B blob data] Feb 11 22:41:21 volumio-black volumiobt[6101]: [157B blob data] Feb 11 22:41:21 volumio-black volumiobt[6101]: [157B blob data] Feb 11 22:41:21 volumio-black volumiobt[6101]: [113B blob data] Feb 11 22:41:21 volumio-black bluetoothd[723]: Path / reserved for Adv Monitor app :1.29 Feb 11 22:41:21 volumio-black volumiobt[6101]: [bluetoothctl]> discoverable on Feb 11 22:41:21 volumio-black volumiobt[6101]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Feb 11 22:41:21 volumio-black volumiobt[6101]: [bluetoothctl]> pairable on Feb 11 22:41:21 volumio-black sudo[6107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 22:41:21 volumio-black sudo[6107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:21 volumio-black bluetoothd[723]: Adv Monitor app :1.29 disconnected from D-Bus Feb 11 22:41:21 volumio-black volumiobt[6101]: [bluetoothctl]> Feb 11 22:41:21 volumio-black volumiobt[6110]: INFO [BTSTART] Registering Bluetooth agent... Feb 11 22:41:21 volumio-black volumio[1218]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 22:41:21 volumio-black volumio[1218]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 22:41:21 volumio-black sudo[6111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 11 22:41:21 volumio-black sudo[6111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:21 volumio-black volumio[1218]: info: Preparing to generate the ALSA configuration file Feb 11 22:41:21 volumio-black volumiobt[6112]: [NEW] Media /org/bluez/hci0 Feb 11 22:41:21 volumio-black volumiobt[6112]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 11 22:41:21 volumio-black volumiobt[6112]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 11 22:41:21 volumio-black volumiobt[6112]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 11 22:41:21 volumio-black systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 22:41:21 volumio-black systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 22:41:21 volumio-black systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:21 volumio-black systemd[1]: shairport-sync.service: Consumed 10.667s CPU time. Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.240 [6074.6074] INFO VolumeManager: [0x1cbb558]: Setting new playback volume: 75 Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.240 [6074.6074] INFO VolumeManager: [0x1cbb558]: Setting new mute state: 0 Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.240 [6074.6074] INFO QobuzConnect: [0x1cbbf28]: Client initialized! Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.240 [6074.6074] INFO SampleApp: Starting Avahi advertising, name: Volumio-black, service name: _qobuz-connect._tcp Feb 11 22:41:21 volumio-black bluetoothd[723]: Adv Monitor app :1.30 disconnected from D-Bus Feb 11 22:41:21 volumio-black volumio[1218]: info: Got Tidal Cloud Configuration Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.286 [6074.6074] INFO LocalConfigManager: [0x1cbae38]: Starting Local Configuration server Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.286 [6074.6074] INFO SampleApp: Starting Local configuration server Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.286 [6074.6074] INFO SampleApp: Connected to UNIX socket client 0x1ca5818 Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.287 [6074.6074] INFO SampleApp: Playback volume changed: 75 Feb 11 22:41:21 volumio-black volumio[1218]: info: Got it Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:21 volumio-black volumio[1218]: info: Asound.conf file unchanged, so no further update is needed Feb 11 22:41:21 volumio-black volumio[1218]: info: Output device has changed, restarting MPD Feb 11 22:41:21 volumio-black volumiobt[6114]: No agent is registered Feb 11 22:41:21 volumio-black volumiobt[6114]: [NEW] Media /org/bluez/hci0 Feb 11 22:41:21 volumio-black volumiobt[6114]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 11 22:41:21 volumio-black volumiobt[6114]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 11 22:41:21 volumio-black volumiobt[6114]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 11 22:41:21 volumio-black systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:21 volumio-black bluetoothd[723]: Path / reserved for Adv Monitor app :1.32 Feb 11 22:41:21 volumio-black 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. Feb 11 22:41:21 volumio-black 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. Feb 11 22:41:21 volumio-black sudo[6107]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:21 volumio-black sudo[6119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 22:41:21 volumio-black sudo[6119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:21 volumio-black bluetoothd[723]: Adv Monitor app :1.32 disconnected from D-Bus Feb 11 22:41:21 volumio-black sudo[6119]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:21 volumio-black volumiobt[6121]: INFO [BTSTART] Agent registered successfully. Feb 11 22:41:21 volumio-black volumio[1218]: info: Output device has changed, restarting Shairport Sync Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:21 volumio-black volumiobt[6123]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Feb 11 22:41:21 volumio-black systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 11 22:41:21 volumio-black sudo[6111]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:21 volumio-black sudo[6124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 22:41:21 volumio-black sudo[6124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:21 volumio-black autossh[6130]: port set to 0, monitoring disabled Feb 11 22:41:21 volumio-black volumio[1218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 22:41:21 volumio-black autossh[6130]: starting ssh (count 1) Feb 11 22:41:21 volumio-black autossh[6130]: ssh child pid is 6147 Feb 11 22:41:21 volumio-black volumio[1218]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 22:41:21 volumio-black volumio[1218]: info: QobuzConnect: setDeactiveState invoked Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:21 volumio-black volumio[1218]: info: Preparing to generate the ALSA configuration file Feb 11 22:41:21 volumio-black systemd[1]: mpd.service: Deactivated successfully. Feb 11 22:41:21 volumio-black systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 22:41:21 volumio-black systemd[1]: mpd.service: Consumed 2.565s CPU time. Feb 11 22:41:21 volumio-black systemd[1]: mpd.socket: Deactivated successfully. Feb 11 22:41:21 volumio-black systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 22:41:21 volumio-black systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 22:41:21 volumio-black volumio[1218]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 11 22:41:21 volumio-black volumio[1218]: info: Reading ALSA contributions from plugins. Feb 11 22:41:21 volumio-black volumio[1218]: info: MPD Permissions set Feb 11 22:41:21 volumio-black systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 22:41:21 volumio-black systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 22:41:21 volumio-black volumio[1218]: info: Remote SSH Started Feb 11 22:41:21 volumio-black sudo[6158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 22:41:21 volumio-black sudo[6158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:21 volumio-black sudo[6158]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:21 volumio-black sudo[6162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 22:41:21 volumio-black sudo[6162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:21 volumio-black systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 11 22:41:21 volumio-black qobuz-connect[6074]: 20260211 22:41:21.801 [6074.6074] INFO SampleApp: Stopping Local configuration server Feb 11 22:41:21 volumio-black sudo[6160]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 22:41:21 volumio-black sudo[6160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 22:41:21 volumio-black sudo[6160]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:21 volumio-black volumio[1218]: info: Shairport-Sync Started Feb 11 22:41:21 volumio-black volumio[1218]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:21 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: Starting Shairport Sync Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:22 volumio-black sudo[6173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 22:41:22 volumio-black sudo[6173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 22:41:22 volumio-black volumio[1218]: info: Asound.conf file written Feb 11 22:41:22 volumio-black systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 22:41:22 volumio-black sudo[6177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 11 22:41:22 volumio-black sudo[6177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 22:41:22 volumio-black systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:22 volumio-black sudo[6177]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:22 volumio-black systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:22 volumio-black sudo[6173]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:22 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 11 22:41:22 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 11 22:41:22 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 22:41:22 volumio-black volumio[1218]: No state is present for card vc4hdmi Feb 11 22:41:22 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 22:41:22 volumio-black volumio[1218]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 11 22:41:22 volumio-black volumio[1218]: Hardware is initialized using a generic method Feb 11 22:41:22 volumio-black volumio[1218]: No state is present for card vc4hdmi Feb 11 22:41:22 volumio-black volumio[1218]: info: Output device has changed, restarting MPD Feb 11 22:41:22 volumio-black volumio[1218]: info: Output device has changed, restarting Shairport Sync Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 22:41:22 volumio-black sudo[6185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 22:41:22 volumio-black sudo[6185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black volumio[1218]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black sudo[6185]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:22 volumio-black sudo[6188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 22:41:22 volumio-black sudo[6188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black volumio[1218]: info: QobuzConnect: setDeactiveState invoked Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:22 volumio-black systemd[1]: mpd.service: Deactivated successfully. Feb 11 22:41:22 volumio-black systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 22:41:22 volumio-black systemd[1]: mpd.socket: Deactivated successfully. Feb 11 22:41:22 volumio-black systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 22:41:22 volumio-black systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 22:41:22 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:22 volumio-black sudo[6215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 22:41:22 volumio-black sudo[6215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black volumio[1218]: info: MPD Permissions set Feb 11 22:41:22 volumio-black volumio[1218]: info: Shairport-Sync Started Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 22:41:22 volumio-black systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:22 volumio-black volumio[1218]: info: Starting Shairport Sync Feb 11 22:41:22 volumio-black sudo[6215]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:22 volumio-black sudo[6226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 22:41:22 volumio-black sudo[6226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black sudo[6225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 22:41:22 volumio-black sudo[6225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:22 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 11 22:41:22 volumio-black systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 22:41:22 volumio-black systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 22:41:22 volumio-black systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:22 volumio-black volumiobt[6131]: INFO:a2dp-agent:Connecting to system bus. Feb 11 22:41:22 volumio-black systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:22 volumio-black sudo[6225]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:22 volumio-black volumio[1218]: info: Shairport-Sync Started Feb 11 22:41:22 volumio-black volumiobt[6131]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Feb 11 22:41:22 volumio-black volumiobt[6131]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Feb 11 22:41:22 volumio-black sudo[6219]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 22:41:22 volumio-black sudo[6219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 22:41:22 volumio-black sudo[6219]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:22 volumio-black volumiobt[6131]: INFO:a2dp-agent:Registering agent. Feb 11 22:41:22 volumio-black volumiobt[6131]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Feb 11 22:41:22 volumio-black volumiobt[6131]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Feb 11 22:41:23 volumio-black qobuz-connect[6074]: 20260211 22:41:23.289 [6074.6074] INFO SampleApp: shat down connection on UNIX socket Feb 11 22:41:23 volumio-black volumio[1218]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 11 22:41:23 volumio-black volumio[1218]: info: QobuzConnect: setDeactiveState invoked Feb 11 22:41:23 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:23 volumio-black systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 11 22:41:23 volumio-black systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 22:41:23 volumio-black systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 22:41:23 volumio-black sudo[6162]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:23 volumio-black sudo[6226]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:23 volumio-black volumio[1218]: info: Executing endpoint qc_getconfig Feb 11 22:41:23 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.443 [6248.6248] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 22:41:23 volumio-black volumio[1218]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 22:41:23 volumio-black volumio[1218]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.452 [6248.6248] INFO VolumeManager: [0x14ac558]: Setting new playback volume: 75 Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.453 [6248.6248] INFO VolumeManager: [0x14ac558]: Setting new mute state: 0 Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.453 [6248.6248] INFO QobuzConnect: [0x14acf28]: Client initialized! Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.454 [6248.6248] INFO SampleApp: Starting Avahi advertising, name: Volumio-black, service name: _qobuz-connect._tcp Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.475 [6248.6248] INFO LocalConfigManager: [0x14abe38]: Starting Local Configuration server Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.476 [6248.6248] INFO SampleApp: Starting Local configuration server Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.476 [6248.6248] INFO SampleApp: Connected to UNIX socket client 0x1496818 Feb 11 22:41:23 volumio-black qobuz-connect[6248]: 20260211 22:41:23.593 [6248.6248] INFO SampleApp: Playback volume changed: 75 Feb 11 22:41:23 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:23 volumio-black volumio[1218]: info: TidalConnect service stoped! Feb 11 22:41:23 volumio-black volumio[1218]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 22:41:23 volumio-black volumio[1218]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 22:41:23 volumio-black sudo[6263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 22:41:23 volumio-black sudo[6263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:23 volumio-black systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 11 22:41:23 volumio-black sudo[6263]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:24 volumio-black volumio[1218]: info: Executing endpoint tc_getconfig Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 11 22:41:24 volumio-black vtcs[6266]: STARTING TidalConnect services, version: 1.6.1 Feb 11 22:41:24 volumio-black vtcs[6266]: STARTED TidalConnect services. Feb 11 22:41:24 volumio-black volumio[1218]: info: Executing endpoint tc_connect Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 11 22:41:24 volumio-black volumio[1218]: info: Connecting to TidalConnect Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::servicePushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:24 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:24 volumio-black volumio[1218]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 22:41:24 volumio-black volumio[1218]: verbose: CURRENT POSITION 0 Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::syncState stateService stop Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:24 volumio-black volumio[1218]: info: No code Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::servicePushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:24 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:24 volumio-black volumio[1218]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 22:41:24 volumio-black volumio[1218]: verbose: CURRENT POSITION 0 Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::syncState stateService stop Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:24 volumio-black volumio[1218]: info: No code Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:24 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:24 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.254Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.255Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" id= title= Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.259Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.260Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" id= title= Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.265Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.268Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" id= title= Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.269Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.270Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" id= title= Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.273Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.276Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" id= title= Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.277Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.277Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:49404 @ 0x1ede870" id= title= Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.296Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.6:49404 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.297Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.6:49404 Feb 11 22:41:24 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:24.303Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.6:55722 Feb 11 22:41:24 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:24 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:24 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:24 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:25 volumio-black mpd[6245]: 2026-02-11T22:41:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 22:41:25 volumio-black systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 22:41:25 volumio-black sudo[6188]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:25 volumio-black sudo[6124]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:25 volumio-black volumio[1218]: error: MPD error: The expression evaluated to a falsy value: Feb 11 22:41:25 volumio-black volumio[1218]: assert.ok(self.idling) Feb 11 22:41:25 volumio-black volumio[1218]: error: The expression evaluated to a falsy value: Feb 11 22:41:25 volumio-black volumio[1218]: assert.ok(self.idling) Feb 11 22:41:25 volumio-black volumio[1218]: error: updateQueue error: null Feb 11 22:41:26 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:26 volumio-black volumio[1218]: info: TidalConnect service started! Feb 11 22:41:29 volumio-black volumio[1218]: info: Disabling MyMusic plugin multiroom Feb 11 22:41:29 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Feb 11 22:41:29 volumio-black volumio[1218]: info: Disabling plugin multiroom Feb 11 22:41:29 volumio-black volumio[1218]: info: Preparing to generate the ALSA configuration file Feb 11 22:41:29 volumio-black volumio[1218]: info: Asound.conf file written Feb 11 22:41:29 volumio-black sudo[6300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 11 22:41:29 volumio-black sudo[6300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:29 volumio-black sudo[6300]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 11 22:41:30 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 11 22:41:30 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 22:41:30 volumio-black volumio[1218]: No state is present for card vc4hdmi Feb 11 22:41:30 volumio-black volumio[1218]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 22:41:30 volumio-black volumio[1218]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 11 22:41:30 volumio-black volumio[1218]: Hardware is initialized using a generic method Feb 11 22:41:30 volumio-black volumio[1218]: No state is present for card vc4hdmi Feb 11 22:41:30 volumio-black volumio[1218]: info: Output device has changed, restarting MPD Feb 11 22:41:30 volumio-black volumio[1218]: info: Output device has changed, restarting Shairport Sync Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:30 volumio-black sudo[6306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 22:41:30 volumio-black sudo[6306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black sudo[6308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 22:41:30 volumio-black sudo[6308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black volumio[1218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 22:41:30 volumio-black volumio[1218]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black sudo[6306]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black volumio[1218]: info: QobuzConnect: setDeactiveState invoked Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:30 volumio-black vtcs[6266]: [2026-02-11 22:41:30.087] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 11 22:41:30 volumio-black vtcs[6266]: [2026-02-11 22:41:30.089] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 11 22:41:30 volumio-black systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 22:41:30 volumio-black sudo[6318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 22:41:30 volumio-black sudo[6318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black sudo[6322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 22:41:30 volumio-black sudo[6322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black volumio[1218]: info: MPD Permissions set Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black systemd[1]: mpd.service: Deactivated successfully. Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black systemd[1]: mpd.service: Consumed 2.926s CPU time. Feb 11 22:41:30 volumio-black systemd[1]: mpd.socket: Deactivated successfully. Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 22:41:30 volumio-black systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 22:41:30 volumio-black sudo[6327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 22:41:30 volumio-black sudo[6327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 22:41:30 volumio-black volumio[1218]: info: Starting Shairport Sync Feb 11 22:41:30 volumio-black sudo[6337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 22:41:30 volumio-black sudo[6337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 22:41:30 volumio-black systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 22:41:30 volumio-black sudo[6327]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 22:41:30 volumio-black systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 11 22:41:30 volumio-black systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 22:41:30 volumio-black systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:30 volumio-black systemd[1]: shairport-sync.service: Consumed 1.572s CPU time. Feb 11 22:41:30 volumio-black sudo[6340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 22:41:30 volumio-black sudo[6340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:30 volumio-black systemd[1]: vtcs.service: Deactivated successfully. Feb 11 22:41:30 volumio-black systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 11 22:41:30 volumio-black sudo[6318]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black sudo[6322]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 11 22:41:30 volumio-black qobuz-connect[6248]: 20260211 22:41:30.448 [6248.6248] INFO SampleApp: Stopping Local configuration server Feb 11 22:41:30 volumio-black sudo[6339]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 22:41:30 volumio-black sudo[6339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 22:41:30 volumio-black sudo[6339]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 22:41:30 volumio-black sudo[6337]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:30 volumio-black volumio[1218]: info: Shairport-Sync Started Feb 11 22:41:30 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:31 volumio-black qobuz-connect[6248]: 20260211 22:41:31.494 [6248.6248] INFO SampleApp: shat down connection on UNIX socket Feb 11 22:41:31 volumio-black volumio[1218]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 11 22:41:31 volumio-black volumio[1218]: info: QobuzConnect: setDeactiveState invoked Feb 11 22:41:31 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:31 volumio-black systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 11 22:41:31 volumio-black systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 22:41:31 volumio-black systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 22:41:31 volumio-black sudo[6340]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:31 volumio-black volumio[1218]: info: Executing endpoint qc_getconfig Feb 11 22:41:31 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.636 [6361.6361] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 22:41:31 volumio-black volumio[1218]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 22:41:31 volumio-black volumio[1218]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.645 [6361.6361] INFO VolumeManager: [0x943558]: Setting new playback volume: 75 Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.645 [6361.6361] INFO VolumeManager: [0x943558]: Setting new mute state: 0 Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.645 [6361.6361] INFO QobuzConnect: [0x943f28]: Client initialized! Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.645 [6361.6361] INFO SampleApp: Starting Avahi advertising, name: Volumio-black, service name: _qobuz-connect._tcp Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.667 [6361.6361] INFO LocalConfigManager: [0x942e38]: Starting Local Configuration server Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.667 [6361.6361] INFO SampleApp: Starting Local configuration server Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.667 [6361.6361] INFO SampleApp: Connected to UNIX socket client 0x92d818 Feb 11 22:41:31 volumio-black qobuz-connect[6361]: 20260211 22:41:31.787 [6361.6361] INFO SampleApp: Playback volume changed: 75 Feb 11 22:41:31 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black mpd[6346]: 2026-02-11T22:41:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 22:41:33 volumio-black systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 22:41:33 volumio-black sudo[6308]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:33 volumio-black volumio[1218]: error: updateQueue error: null Feb 11 22:41:33 volumio-black volumio[1218]: info: TidalConnect service stoped! Feb 11 22:41:33 volumio-black volumio[1218]: info: TidalConnect service stoped! Feb 11 22:41:33 volumio-black volumio[1218]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 22:41:33 volumio-black volumio[1218]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 22:41:33 volumio-black sudo[6378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 22:41:33 volumio-black sudo[6378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:33 volumio-black systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 11 22:41:33 volumio-black sudo[6378]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:33 volumio-black volumio[1218]: info: Executing endpoint tc_getconfig Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 11 22:41:33 volumio-black vtcs[6381]: STARTING TidalConnect services, version: 1.6.1 Feb 11 22:41:33 volumio-black vtcs[6381]: STARTED TidalConnect services. Feb 11 22:41:33 volumio-black volumio[1218]: info: Executing endpoint tc_connect Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 11 22:41:33 volumio-black volumio[1218]: info: Connecting to TidalConnect Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::servicePushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:33 volumio-black volumio[1218]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 22:41:33 volumio-black volumio[1218]: verbose: CURRENT POSITION 0 Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::syncState stateService stop Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black volumio[1218]: info: No code Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::servicePushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black volumio[1218]: info: CorePlayQueue::getTrack 0 Feb 11 22:41:33 volumio-black volumio[1218]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 22:41:33 volumio-black volumio[1218]: verbose: CURRENT POSITION 0 Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::syncState stateService stop Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black volumio[1218]: info: No code Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreStateMachine::pushState Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioPushState Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:41:33 volumio-black volumio[1218]: info: MRS: Pushing multiroomSync output Feb 11 22:41:33 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetState Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.853Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.854Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" id= title= Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.859Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.859Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" id= title= Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.862Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.863Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" id= title= Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.868Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.869Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" id= title= Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.874Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.875Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" id= title= Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.884Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.884Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:55722 @ 0x1ca02d0" id= title= Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.911Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.6:55722 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.911Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.6:55722 Feb 11 22:41:33 volumio-black volumio5-onboarding[1388]: time=2026-02-11T22:41:33.924Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.6:44310 Feb 11 22:41:33 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:33 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:33 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:33 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:33 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:33 volumio-black volumio[1218]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ImGmS0bXHEhFOz7D9QQ92lnGl323.6d7069e456819f30e781a1ce039cba56.state.status' Feb 11 22:41:34 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:35 volumio-black volumio[1218]: info: Disabling MyMusic plugin cd_controller Feb 11 22:41:35 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller Feb 11 22:41:35 volumio-black volumio[1218]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Feb 11 22:41:35 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 22:41:35 volumio-black volumio[1218]: Cannot find translation for source USB Audio CODEC Feb 11 22:41:35 volumio-black volumio[1218]: info: Stopping UDEV Watcher for CD Feb 11 22:41:35 volumio-black volumio[1218]: info: Disabling plugin cd_controller Feb 11 22:41:36 volumio-black kernel: hwmon hwmon1: Undervoltage detected! Feb 11 22:41:36 volumio-black volumio[1218]: info: TidalConnect service started! Feb 11 22:41:36 volumio-black volumiossh-tunnel[6147]: Warning: Permanently added '[eu1.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 11 22:41:38 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:40 volumio-black volumio[1218]: info: Disabling MyMusic plugin smart_inputs Feb 11 22:41:40 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service smart_inputs Feb 11 22:41:40 volumio-black volumio[1218]: info: Removeing all smart Inputs Feb 11 22:41:40 volumio-black volumio[1218]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesUSB Audio CODEC Feb 11 22:41:40 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 22:41:40 volumio-black volumio[1218]: info: Disabling plugin smart_inputs Feb 11 22:41:42 volumio-black volumio[1218]: info: Disabling MyMusic plugin tidalconnect Feb 11 22:41:42 volumio-black volumio[1218]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Feb 11 22:41:42 volumio-black vtcs[6381]: [2026-02-11 22:41:42.094] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 11 22:41:42 volumio-black vtcs[6381]: [2026-02-11 22:41:42.094] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 11 22:41:42 volumio-black volumio[1218]: info: Disabling plugin tidalconnect Feb 11 22:41:42 volumio-black sudo[6414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 22:41:42 volumio-black sudo[6414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:41:42 volumio-black systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 11 22:41:42 volumio-black systemd[1]: vtcs.service: Killing process 6383 (vtcs) with signal SIGKILL. Feb 11 22:41:42 volumio-black systemd[1]: vtcs.service: Deactivated successfully. Feb 11 22:41:42 volumio-black systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 11 22:41:42 volumio-black sudo[6414]: pam_unix(sudo:session): session closed for user root Feb 11 22:41:42 volumio-black kernel: hwmon hwmon1: Voltage normalised Feb 11 22:41:42 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:45 volumio-black volumio[1218]: info: TidalConnect service stoped! Feb 11 22:41:46 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:50 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:50 volumio-black kernel: hwmon hwmon1: Undervoltage detected! Feb 11 22:41:54 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:57 volumio-black kernel: hwmon hwmon1: Voltage normalised Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:59 volumio-black volumio[1218]: info: Retrieving Cloud Streaming UI Feb 11 22:41:59 volumio-black volumio[1218]: info: Getting Tidal Cloud Configuration Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:59 volumio-black volumio[1218]: info: Getting Qobuz Cloud Configuration Feb 11 22:41:59 volumio-black volumio[1218]: info: Asking plugin for UI Config Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:59 volumio-black volumio[1218]: info: Getting Spotify Cloud Configuration Feb 11 22:41:59 volumio-black volumio[1218]: info: Asking plugin for UI Config Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:59 volumio-black volumio[1218]: info: Saving Spotify Acccount Feb 11 22:41:59 volumio-black volumio[1218]: info: Got it Feb 11 22:41:59 volumio-black volumio[1218]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 22:41:59 volumio-black volumio[1218]: info: Got Tidal Cloud Configuration Feb 11 22:41:59 volumio-black volumio[1218]: info: Got it Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:41:59 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 11 22:42:01 volumio-black kernel: hwmon hwmon1: Undervoltage detected! Feb 11 22:42:03 volumio-black volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 22:42:06 volumio-black volumio[1218]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Feb 11 22:42:06 volumio-black volumio[1218]: error: Failed to ping endpoint eu6.myvolumio.org : unknown error Feb 11 22:42:06 volumio-black volumio[1218]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Feb 11 22:42:06 volumio-black volumio[1218]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Feb 11 22:42:06 volumio-black volumio[1218]: error: Failed to ping endpoint us4.myvolumio.org : unknown error Feb 11 22:42:07 volumio-black volumio[1218]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Feb 11 22:42:07 volumio-black volumio[1218]: error: Failed to ping endpoint eu6.myvolumio.org : unknown error Feb 11 22:42:07 volumio-black volumio[1218]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Feb 11 22:42:07 volumio-black volumio[1218]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Feb 11 22:42:07 volumio-black volumio[1218]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Feb 11 22:42:07 volumio-black volumio[1218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 22:42:07 volumio-black volumio[1218]: Error: Unable to resolve or reject the same promise twice Feb 11 22:42:07 volumio-black volumio[1218]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 11 22:42:07 volumio-black volumio[1218]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Feb 11 22:42:07 volumio-black volumio[1218]: at Socket.emit (node:events:514:28) Feb 11 22:42:07 volumio-black volumio[1218]: at addChunk (node:internal/streams/readable:343:12) Feb 11 22:42:07 volumio-black volumio[1218]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 11 22:42:07 volumio-black volumio[1218]: at Readable.push (node:internal/streams/readable:253:10) Feb 11 22:42:07 volumio-black volumio[1218]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 11 22:42:07 volumio-black volumio[1218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 22:42:08 volumio-black sudo[6466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 22:41' Feb 11 22:42:08 volumio-black sudo[6466]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"