Apr 12 11:33:40 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 11:33:40 volumiored volumio[968]: info: Received Get System Info Apr 12 11:33:40 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 11:33:40 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 11:33:40 volumiored volumio[968]: info: Discovery: Getting this device information Apr 12 11:33:40 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:40 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 11:33:42 volumiored volumio[968]: info: MyVolumio Plan changed: premium Apr 12 11:33:42 volumiored volumio[968]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 12 11:33:42 volumiored volumio[968]: info: Removing browser output: myVolumio user plan is not superstar Apr 12 11:33:42 volumiored volumio[968]: info: Removing audio output: Apr 12 11:33:42 volumiored volumio[968]: info: MYVOLUMIO: Adding device Apr 12 11:33:42 volumiored volumio[968]: info: MYVOLUMIO: Evaluating Server Apr 12 11:33:43 volumiored volumio[968]: info: Remote config written successfully Apr 12 11:33:43 volumiored volumio[968]: info: Starting Tunnel 1 Apr 12 11:33:43 volumiored volumio[968]: info: Starting Tunnel Connection Checker Apr 12 11:33:43 volumiored volumio[968]: info: MYVolumio Device disabled Apr 12 11:33:43 volumiored volumio[968]: info: Setting Geolocation for MyVolumio to eu7 Apr 12 11:33:43 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:43 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:43 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:44 volumiored volumio[968]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 12 11:33:44 volumiored volumio[968]: info: Updating MyVolumio device info Apr 12 11:33:44 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:44 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:44 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:45 volumiored volumio[968]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 12 11:33:46 volumiored sudo[2080]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 11:33:46 volumiored sudo[2080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:46 volumiored sudo[2080]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:46 volumiored sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 11:33:46 volumiored sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:47 volumiored sudo[2082]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:47 volumiored volumio[968]: verbose: New Socket.io Connection to 192.168.0.58 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: Received Get System Info Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 11:33:47 volumiored volumio[968]: info: Discovery: Getting this device information Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 11:33:47 volumiored volumio[968]: info: Received Get System Info Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 11:33:47 volumiored volumio[968]: info: Discovery: Getting this device information Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: Listing playlists Apr 12 11:33:47 volumiored volumio[968]: info: Received Get System Info Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 11:33:47 volumiored volumio[968]: info: Discovery: Getting this device information Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:47 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 11:33:47 volumiored volumio[968]: error: [squeezelite_mc] Proxy: invalid URL (http%3A%2F%2F192.168.0.57%3A9000%2Fimageproxy%2Fhttps%253A%252F%252Fstatic.qobuz.com%252Fimages%252Fcovers%252Fdc%252Fv7%252Ficdl9mgwhv7dc_600.jpg%2Fimage.jpg) Apr 12 11:33:48 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:48 volumiored sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 12 11:33:48 volumiored sudo[2095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:48 volumiored 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. Apr 12 11:33:48 volumiored 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. Apr 12 11:33:48 volumiored systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 12 11:33:48 volumiored sudo[2095]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:48 volumiored volumio[968]: info: Remote SSH Started Apr 12 11:33:48 volumiored autossh[2098]: port set to 0, monitoring disabled Apr 12 11:33:48 volumiored autossh[2098]: starting ssh (count 1) Apr 12 11:33:48 volumiored autossh[2098]: ssh child pid is 2101 Apr 12 11:33:48 volumiored volumiossh-tunnel[2101]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Apr 12 11:33:48 volumiored volumio[968]: info: MYVOLUMIO: Adding device Apr 12 11:33:48 volumiored volumio[968]: info: MYVOLUMIO: Evaluating Server Apr 12 11:33:48 volumiored volumio[968]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 12 11:33:49 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:49 volumiored volumio[968]: info: Setting Geolocation for MyVolumio to eu4 Apr 12 11:33:49 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:49 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:49 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:49 volumiored volumio[968]: info: MYVolumio Device enabled Apr 12 11:33:49 volumiored volumio[968]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 12 11:33:49 volumiored volumio[968]: info: MyVolumio status changed Apr 12 11:33:49 volumiored volumio[968]: info: Streaming services startup Apr 12 11:33:49 volumiored volumio[968]: info: Starting Streaming Daemon Apr 12 11:33:49 volumiored volumio[968]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Apr 12 11:33:49 volumiored volumio[968]: info: Successfully Added MyVolumio device Apr 12 11:33:50 volumiored sudo[2135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 11:33:50 volumiored sudo[2135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:50 volumiored sudo[2135]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:50 volumiored volumio[968]: error: Cannot start Volumio Streaming Daemon Apr 12 11:33:50 volumiored volumio[968]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 11:33:50 volumiored volumio[968]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 11:33:50 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 12 11:33:50 volumiored volumio[968]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 12 11:33:50 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 12 11:33:52 volumiored sudo[2139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 12 11:33:52 volumiored sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:52 volumiored sudo[2139]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:52 volumiored volumio[968]: info: MRS: MultiRoom plugin initialized Apr 12 11:33:52 volumiored volumio[968]: info: MRS: STOPPING SNAPCLIENT Apr 12 11:33:52 volumiored volumio[968]: info: MRS: Snap server stop Apr 12 11:33:52 volumiored volumio[968]: info: MRS: STOPPING volumioStreaming Apr 12 11:33:52 volumiored sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 12 11:33:52 volumiored sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 12 11:33:52 volumiored sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 12 11:33:52 volumiored sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:52 volumiored sudo[2157]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:52 volumiored sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 12 11:33:52 volumiored sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:52 volumiored sudo[2163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 12 11:33:52 volumiored sudo[2158]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 12 11:33:52 volumiored sudo[2162]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:52 volumiored sudo[2163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:52 volumiored sudo[2163]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 12 11:33:52 volumiored volumio[968]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 12 11:33:52 volumiored volumio[968]: info: Preparing to generate the ALSA configuration file Apr 12 11:33:52 volumiored volumio[968]: info: Updating MyVolumio device info Apr 12 11:33:52 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:52 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:53 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:53 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 12 11:33:53 volumiored volumio[968]: info: MRS: Removed streaming files Apr 12 11:33:53 volumiored volumio[968]: info: MRS: volumioStreaming STOPPED Apr 12 11:33:53 volumiored volumio[968]: info: MRS: SNAPSERVER STOPPED Apr 12 11:33:53 volumiored volumio[968]: info: MRS: SNAPCLIENT STOPPED Apr 12 11:33:53 volumiored volumio[968]: info: Asound.conf file unchanged, so no further update is needed Apr 12 11:33:53 volumiored volumio[968]: info: Output device has changed, restarting MPD Apr 12 11:33:53 volumiored volumio[968]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 11:33:53 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:53 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 12 11:33:53 volumiored volumio[968]: ------------------------------------ BT MESSAGE: [FUNC] onStart Apr 12 11:33:53 volumiored volumio[968]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Apr 12 11:33:53 volumiored volumio[968]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Apr 12 11:33:53 volumiored volumio[968]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Apr 12 11:33:53 volumiored volumio[968]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Apr 12 11:33:53 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 12 11:33:53 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 12 11:33:53 volumiored volumio[968]: info: MRS: Pushing multiroomSync output for this device Apr 12 11:33:53 volumiored volumio[968]: info: MRS: Pushing multiroomSync output Apr 12 11:33:53 volumiored sudo[2169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 11:33:53 volumiored volumio[968]: info: Adding audio output: Apr 12 11:33:53 volumiored volumio[968]: info: Adding audio output: Apr 12 11:33:53 volumiored sudo[2169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:53 volumiored sudo[2171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 11:33:53 volumiored volumio[968]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 11:33:53 volumiored sudo[2171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:53 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:53 volumiored sudo[2169]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:53 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 12 11:33:53 volumiored volumio[968]: info: Adding METAVOLUMIO REST API Endpoints Apr 12 11:33:53 volumiored volumio[968]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 12 11:33:53 volumiored volumio[968]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 12 11:33:53 volumiored volumio[968]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 12 11:33:53 volumiored volumio[968]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 12 11:33:53 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 12 11:33:53 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 12 11:33:53 volumiored volumio[968]: info: Preparing CD Folders Apr 12 11:33:53 volumiored volumio[968]: info: Adding CD REST API Endpoints Apr 12 11:33:53 volumiored volumio[968]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 12 11:33:53 volumiored volumio[968]: info: Starting UDEV Watcher for CD Apr 12 11:33:53 volumiored volumio[968]: info: Detecting CD presence with UDEV Apr 12 11:33:53 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 12 11:33:53 volumiored systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 11:33:53 volumiored systemd[1]: mpd.service: Deactivated successfully. Apr 12 11:33:53 volumiored systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 11:33:53 volumiored systemd[1]: mpd.service: Consumed 3.156s CPU time. Apr 12 11:33:53 volumiored systemd[1]: mpd.socket: Deactivated successfully. Apr 12 11:33:53 volumiored systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 11:33:53 volumiored systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 11:33:53 volumiored systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 11:33:53 volumiored systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 11:33:53 volumiored sudo[2184]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 11:33:53 volumiored sudo[2184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 11:33:53 volumiored sudo[2184]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:55 volumiored mpd[2186]: 2026-04-12T11:33:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 11:33:55 volumiored systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 11:33:55 volumiored sudo[2171]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:58 volumiored volumio[968]: warn: [cd-plugin] cdspeedctl: device or media not ready Apr 12 11:33:58 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 12 11:33:58 volumiored volumio[968]: info: Adding inputs REST Endpoints Apr 12 11:33:58 volumiored volumio[968]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 12 11:33:58 volumiored volumio[968]: info: Scanning Audio Inputs Apr 12 11:33:58 volumiored volumio[968]: info: Checking against Known Cards name Apr 12 11:33:58 volumiored volumio[968]: info: Adding Server instance for streaming Apr 12 11:33:58 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 12 11:33:58 volumiored volumio[968]: error: Hi Res Audio Failed Login: Missing Login Data Apr 12 11:33:58 volumiored volumio[968]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 12 11:33:58 volumiored volumio[968]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 12 11:33:58 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 12 11:33:58 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 12 11:33:58 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 12 11:33:58 volumiored volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Apr 12 11:33:58 volumiored volumio[968]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Apr 12 11:33:58 volumiored sudo[2202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 11:33:58 volumiored sudo[2202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:58 volumiored volumio[968]: info: QobuzConnect: Starting Qobuz Connect socket and service Apr 12 11:33:58 volumiored sudo[2202]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:58 volumiored sudo[2209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 11:33:58 volumiored sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:58 volumiored volumio[968]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Apr 12 11:33:58 volumiored volumio[968]: info: Adding TIDAL REST API Endpoints Apr 12 11:33:58 volumiored volumio[968]: info: Stopping AccessToken refresher cron for QOBUZ Apr 12 11:33:58 volumiored sudo[2209]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:58 volumiored sudo[2213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 11:33:58 volumiored sudo[2213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:58 volumiored volumio[968]: info: AccessToken refresher cron started for QOBUZ Apr 12 11:33:58 volumiored volumio[968]: info: Adding QOBUZ REST API Endpoints Apr 12 11:33:58 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:58 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:58 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:58 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 11:33:58 volumiored volumio[968]: info: MRS: Getting audio outputs on start Apr 12 11:33:58 volumiored volumio[968]: info: MRS: Requesting all other devices output Apr 12 11:33:58 volumiored systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 11:33:58 volumiored sudo[2213]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 12 11:33:59 volumiored volumio[968]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 12 11:33:59 volumiored volumio[968]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Apr 12 11:33:59 volumiored volumio[968]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 12 11:33:59 volumiored volumio[968]: info: MPD Permissions set Apr 12 11:33:59 volumiored volumio[968]: info: Executing endpoint qc_getconfig Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 12 11:33:59 volumiored sudo[2217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 12 11:33:59 volumiored sudo[2217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.140 [2215.2215] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored sudo[2217]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:59 volumiored volumiobt[2238]: INFO [BTSTART] Ensuring Bluetooth directory exists... Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.240 [2215.2215] INFO VolumeManager: [0x1e48f78]: Setting new playback volume: 75 Apr 12 11:33:59 volumiored sudo[2239]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.245 [2215.2215] INFO VolumeManager: [0x1e48f78]: Setting new mute state: 0 Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.246 [2215.2215] INFO AudioStreamManager: [0x1e48cd0]: Setting new audio download buffer size: 1048576 Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.246 [2215.2215] INFO QobuzConnect: [0x1e49840]: Client initialized! Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.246 [2215.2215] INFO SampleApp: Starting Avahi advertising, name: VolumioRED, service name: _qobuz-connect._tcp Apr 12 11:33:59 volumiored sudo[2239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.257 [2215.2215] INFO LocalConfigManager: [0x1e489f8]: Starting Local Configuration server Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.257 [2215.2215] INFO SampleApp: Starting Local configuration server Apr 12 11:33:59 volumiored sudo[2239]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.258 [2215.2215] INFO SampleApp: Connected to UNIX socket client 0x1e33818 Apr 12 11:33:59 volumiored sudo[2244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Apr 12 11:33:59 volumiored sudo[2244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:59 volumiored sudo[2244]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:59 volumiored volumiobt[2250]: INFO [BTSTART] Powering on Bluetooth if needed... Apr 12 11:33:59 volumiored qobuz-connect[2215]: 20260412 11:33:59.290 [2215.2215] INFO SampleApp: Playback volume changed: 75 Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored volumiobt[2253]: No default controller available Apr 12 11:33:59 volumiored volumio[968]: error: updateQueue error: null Apr 12 11:33:59 volumiored volumio[968]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 12 11:33:59 volumiored volumio[968]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 12 11:33:59 volumiored volumio[968]: info: Successfully Updated MyVolumio device Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:59 volumiored volumio[968]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Apr 12 11:33:59 volumiored volumio[968]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Apr 12 11:33:59 volumiored volumio[968]: info: Preparing to generate the ALSA configuration file Apr 12 11:33:59 volumiored volumio[968]: info: MRS: Found cast device: Chromecast-Audio-b4beda9dfad41514bfc0a709b50cbc23 Apr 12 11:33:59 volumiored volumio[968]: info: Adding audio output: Apr 12 11:33:59 volumiored volumio[968]: info: Asound.conf file unchanged, so no further update is needed Apr 12 11:33:59 volumiored volumio[968]: info: Output device has changed, restarting MPD Apr 12 11:33:59 volumiored volumio[968]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:33:59 volumiored sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 11:33:59 volumiored sudo[2258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 11:33:59 volumiored sudo[2258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:59 volumiored volumio[968]: info: QobuzConnect: setDeactiveState invoked Apr 12 11:33:59 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:33:59 volumiored volumio[968]: info: Preparing to generate the ALSA configuration file Apr 12 11:33:59 volumiored sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:33:59 volumiored sudo[2258]: pam_unix(sudo:session): session closed for user root Apr 12 11:33:59 volumiored systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 11:33:59 volumiored systemd[1]: mpd.service: Deactivated successfully. Apr 12 11:33:59 volumiored systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 11:33:59 volumiored systemd[1]: mpd.service: Consumed 1.318s CPU time. Apr 12 11:33:59 volumiored systemd[1]: mpd.socket: Deactivated successfully. Apr 12 11:33:59 volumiored systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 11:33:59 volumiored systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 11:33:59 volumiored volumio[968]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 11:33:59 volumiored volumio[968]: info: Reading ALSA contributions from plugins. Apr 12 11:33:59 volumiored volumio[968]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 12 11:33:59 volumiored volumio[968]: info: MPD Permissions set Apr 12 11:33:59 volumiored systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 11:33:59 volumiored systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 11:33:59 volumiored sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 11:33:59 volumiored sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored sudo[2271]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 11:34:00 volumiored sudo[2271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 11:34:00 volumiored sudo[2271]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored sudo[2269]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:00 volumiored sudo[2280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 11:34:00 volumiored sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored volumio[968]: info: Asound.conf file written Apr 12 11:34:00 volumiored systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 12 11:34:00 volumiored qobuz-connect[2215]: 20260412 11:34:00.269 [2215.2215] INFO SampleApp: Stopping Local configuration server Apr 12 11:34:00 volumiored volumiobt[2285]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Apr 12 11:34:00 volumiored volumiobt[2286]: [83B blob data] Apr 12 11:34:00 volumiored volumiobt[2286]: No default controller available Apr 12 11:34:00 volumiored volumiobt[2286]: [bluetoothctl]> pairable on Apr 12 11:34:00 volumiored volumiobt[2286]: No default controller available Apr 12 11:34:00 volumiored volumiobt[2286]: [bluetoothctl]> Apr 12 11:34:00 volumiored volumiobt[2287]: INFO [BTSTART] Registering Bluetooth agent... Apr 12 11:34:00 volumiored sudo[2284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 11:34:00 volumiored sudo[2284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored sudo[2284]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:00 volumiored volumiobt[2290]: No agent is registered Apr 12 11:34:00 volumiored volumiobt[2294]: INFO [BTSTART] Agent registered successfully. Apr 12 11:34:00 volumiored volumiobt[2295]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Apr 12 11:34:00 volumiored volumio[968]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 11:34:00 volumiored volumio[968]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 11:34:00 volumiored volumio[968]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 12 11:34:00 volumiored volumio[968]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Apr 12 11:34:00 volumiored volumio[968]: info: Output device has changed, restarting MPD Apr 12 11:34:00 volumiored volumio[968]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: QobuzConnect: setDeactiveState invoked Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:00 volumiored sudo[2298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 11:34:00 volumiored sudo[2298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored sudo[2298]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:00 volumiored sudo[2300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 11:34:00 volumiored sudo[2300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored systemd[1]: mpd.service: Deactivated successfully. Apr 12 11:34:00 volumiored systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 11:34:00 volumiored systemd[1]: mpd.socket: Deactivated successfully. Apr 12 11:34:00 volumiored systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 11:34:00 volumiored systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 11:34:00 volumiored volumio[968]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Apr 12 11:34:00 volumiored sudo[2310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 11:34:00 volumiored sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored volumio[968]: info: [squeezelite_mc] Proxy server stopped Apr 12 11:34:00 volumiored volumio[968]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 12 11:34:00 volumiored volumio[968]: info: MPD Permissions set Apr 12 11:34:00 volumiored sudo[2314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Apr 12 11:34:00 volumiored sudo[2314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 11:34:00 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 11:34:00 volumiored systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 11:34:00 volumiored systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 11:34:00 volumiored systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Apr 12 11:34:00 volumiored sudo[2310]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:00 volumiored sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 11:34:00 volumiored sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:01 volumiored sudo[2321]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 11:34:01 volumiored sudo[2321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 11:34:01 volumiored sudo[2321]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored systemd[1]: squeezelite.service: Deactivated successfully. Apr 12 11:34:01 volumiored systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Apr 12 11:34:01 volumiored systemd[1]: squeezelite.service: Consumed 1.421s CPU time. Apr 12 11:34:01 volumiored sudo[2314]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored volumiobt[2296]: 2026-04-12 11:34:01 a2dp-agent [INFO] Connecting to system D-Bus Apr 12 11:34:01 volumiored volumiobt[2296]: 2026-04-12 11:34:01 a2dp-agent [INFO] Connected to system D-Bus Apr 12 11:34:01 volumiored volumiobt[2296]: 2026-04-12 11:34:01 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Apr 12 11:34:01 volumiored volumiobt[2296]: 2026-04-12 11:34:01 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Apr 12 11:34:01 volumiored volumiobt[2296]: Traceback (most recent call last): Apr 12 11:34:01 volumiored volumiobt[2296]: File "/bin/bt/a2dp-agent", line 225, in main Apr 12 11:34:01 volumiored volumiobt[2296]: asyncio.run(_run()) Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Apr 12 11:34:01 volumiored volumiobt[2296]: return runner.run(main) Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Apr 12 11:34:01 volumiored volumiobt[2296]: return self._loop.run_until_complete(task) Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Apr 12 11:34:01 volumiored volumiobt[2296]: return future.result() Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/bin/bt/a2dp-agent", line 175, in _run Apr 12 11:34:01 volumiored volumiobt[2296]: adapter_path = await find_adapter(bus) Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Apr 12 11:34:01 volumiored volumiobt[2296]: raise Exception("Bluetooth adapter not found") Apr 12 11:34:01 volumiored volumiobt[2296]: Exception: Bluetooth adapter not found Apr 12 11:34:01 volumiored volumiobt[2296]: Traceback (most recent call last): Apr 12 11:34:01 volumiored volumiobt[2296]: File "/bin/bt/a2dp-agent", line 234, in Apr 12 11:34:01 volumiored volumiobt[2296]: main() Apr 12 11:34:01 volumiored volumiobt[2296]: File "/bin/bt/a2dp-agent", line 225, in main Apr 12 11:34:01 volumiored volumiobt[2296]: asyncio.run(_run()) Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Apr 12 11:34:01 volumiored volumiobt[2296]: return runner.run(main) Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Apr 12 11:34:01 volumiored volumiobt[2296]: return self._loop.run_until_complete(task) Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Apr 12 11:34:01 volumiored volumiobt[2296]: return future.result() Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/bin/bt/a2dp-agent", line 175, in _run Apr 12 11:34:01 volumiored volumiobt[2296]: adapter_path = await find_adapter(bus) Apr 12 11:34:01 volumiored volumiobt[2296]: ^^^^^^^^^^^^^^^^^^^^^^^ Apr 12 11:34:01 volumiored volumiobt[2296]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Apr 12 11:34:01 volumiored volumiobt[2296]: raise Exception("Bluetooth adapter not found") Apr 12 11:34:01 volumiored volumiobt[2296]: Exception: Bluetooth adapter not found Apr 12 11:34:01 volumiored qobuz-connect[2215]: 20260412 11:34:01.270 [2215.2215] INFO SampleApp: shat down connection on UNIX socket Apr 12 11:34:01 volumiored volumio[968]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 12 11:34:01 volumiored volumio[968]: info: QobuzConnect: setDeactiveState invoked Apr 12 11:34:01 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:01 volumiored systemd[1]: qobuz-connect.service: Deactivated successfully. Apr 12 11:34:01 volumiored systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 11:34:01 volumiored systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 11:34:01 volumiored sudo[2280]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored sudo[2322]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Apr 12 11:34:01 volumiored systemd[1]: volumiobt.service: Failed with result 'exit-code'. Apr 12 11:34:01 volumiored volumio[968]: info: Executing endpoint qc_getconfig Apr 12 11:34:01 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.438 [2326.2326] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 12 11:34:01 volumiored volumio[968]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 12 11:34:01 volumiored volumio[968]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.442 [2326.2326] INFO VolumeManager: [0x1c8af78]: Setting new playback volume: 75 Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.442 [2326.2326] INFO VolumeManager: [0x1c8af78]: Setting new mute state: 0 Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.442 [2326.2326] INFO AudioStreamManager: [0x1c8acd0]: Setting new audio download buffer size: 1048576 Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.442 [2326.2326] INFO QobuzConnect: [0x1c8b840]: Client initialized! Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.442 [2326.2326] INFO SampleApp: Starting Avahi advertising, name: VolumioRED, service name: _qobuz-connect._tcp Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.454 [2326.2326] INFO LocalConfigManager: [0x1c8a9f8]: Starting Local Configuration server Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.454 [2326.2326] INFO SampleApp: Starting Local configuration server Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.454 [2326.2326] INFO SampleApp: Connected to UNIX socket client 0x1c75818 Apr 12 11:34:01 volumiored qobuz-connect[2326]: 20260412 11:34:01.588 [2326.2326] INFO SampleApp: Playback volume changed: 75 Apr 12 11:34:01 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:01 volumiored systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 1. Apr 12 11:34:01 volumiored systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Apr 12 11:34:01 volumiored volumio[968]: info: TidalConnect service stoped! Apr 12 11:34:01 volumiored volumio[968]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 11:34:01 volumiored volumio[968]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 11:34:01 volumiored volumio[968]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 11:34:01 volumiored sudo[2342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 11:34:01 volumiored sudo[2342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:01 volumiored sudo[2344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 11:34:01 volumiored sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:01 volumiored systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Apr 12 11:34:01 volumiored volumiobt[2347]: INFO [BTSTART] Ensuring Bluetooth directory exists... Apr 12 11:34:01 volumiored sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Apr 12 11:34:01 volumiored sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:01 volumiored sudo[2348]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored sudo[2342]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored sudo[2350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Apr 12 11:34:01 volumiored sudo[2350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 11:34:01 volumiored volumio[968]: info: Disabling plugin squeezelite_mc Apr 12 11:34:01 volumiored sudo[2350]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored volumio[968]: info: Done. Apr 12 11:34:01 volumiored systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 12 11:34:01 volumiored sudo[2344]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:01 volumiored volumiobt[2353]: INFO [BTSTART] Powering on Bluetooth if needed... Apr 12 11:34:01 volumiored volumiobt[2358]: No default controller available Apr 12 11:34:02 volumiored volumio[968]: info: Executing endpoint tc_getconfig Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 12 11:34:02 volumiored vtcs[2354]: STARTING TidalConnect services, version: 1.6.1 Apr 12 11:34:02 volumiored vtcs[2354]: STARTED TidalConnect services. Apr 12 11:34:02 volumiored volumio[968]: info: Executing endpoint tc_connect Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 12 11:34:02 volumiored volumio[968]: info: Connecting to TidalConnect Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::servicePushState Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::pushState Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioPushState Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output update for this device Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:02 volumiored volumio[968]: info: CorePlayQueue::getTrack 0 Apr 12 11:34:02 volumiored volumio[968]: 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} Apr 12 11:34:02 volumiored volumio[968]: verbose: CURRENT POSITION 0 Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::syncState stateService stop Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::syncState currentStatus play Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioPushState Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output update for this device Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::stPlaybackTimer Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::servicePushState Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::pushState Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioPushState Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output update for this device Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:02 volumiored volumio[968]: info: CorePlayQueue::getTrack 0 Apr 12 11:34:02 volumiored volumio[968]: 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} Apr 12 11:34:02 volumiored volumio[968]: verbose: CURRENT POSITION 0 Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::syncState stateService stop Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::syncState currentStatus stop Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::pushState Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioPushState Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output update for this device Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:02 volumiored volumio[968]: info: No code Apr 12 11:34:02 volumiored volumio[968]: info: CoreStateMachine::pushState Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioPushState Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output update for this device Apr 12 11:34:02 volumiored volumio[968]: info: MRS: Pushing multiroomSync output Apr 12 11:34:02 volumiored volumio[968]: info: CoreCommandRouter::volumioGetState Apr 12 11:34:02 volumiored volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 11:34:02 volumiored volumio[968]: TypeError: Cannot read properties of null (reading 'logger') Apr 12 11:34:02 volumiored volumio[968]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:65:86) Apr 12 11:34:02 volumiored volumio[968]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_stdLogError (/data/plugins/music_service/squeezelite_mc/dist/index.js:716:36) Apr 12 11:34:02 volumiored volumio[968]: at /data/plugins/music_service/squeezelite_mc/dist/index.js:1216:121 Apr 12 11:34:02 volumiored volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 11:34:02 volumiored mpd[2325]: 2026-04-12T11:34:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 11:34:02 volumiored systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 11:34:02 volumiored sudo[2300]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:02 volumiored sudo[2259]: pam_unix(sudo:session): session closed for user root Apr 12 11:34:02 volumiored sudo[2390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 11:33' Apr 12 11:34:02 volumiored sudo[2390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"