Aug 28 14:34:04 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 28 14:34:04 volumiokoupelna sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 28 14:34:04 volumiokoupelna sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:04 volumiokoupelna sudo[1632]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:04 volumiokoupelna volumio[949]: info: MRS: MultiRoom plugin initialized Aug 28 14:34:04 volumiokoupelna volumio[949]: info: MRS: STOPPING SNAPCLIENT Aug 28 14:34:04 volumiokoupelna volumio[949]: info: MRS: Snap server stop Aug 28 14:34:05 volumiokoupelna volumio[949]: info: MRS: STOPPING volumioStreaming Aug 28 14:34:05 volumiokoupelna sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 28 14:34:05 volumiokoupelna sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:05 volumiokoupelna sudo[1651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 28 14:34:05 volumiokoupelna sudo[1651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:05 volumiokoupelna sudo[1653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 28 14:34:05 volumiokoupelna sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 28 14:34:05 volumiokoupelna sudo[1655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 14:34:05 volumiokoupelna sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:05 volumiokoupelna sudo[1655]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 28 14:34:05 volumiokoupelna sudo[1651]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:05 volumiokoupelna sudo[1649]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:05 volumiokoupelna sudo[1653]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 28 14:34:05 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Aug 28 14:34:05 volumiokoupelna volumio[949]: info: Preparing to generate the ALSA configuration file Aug 28 14:34:06 volumiokoupelna volumio[949]: info: Updating MyVolumio device info Aug 28 14:34:06 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:34:06 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:34:06 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:34:06 volumiokoupelna volumio[949]: info: Successfully Added MyVolumio device Aug 28 14:34:06 volumiokoupelna volumio[949]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 28 14:34:06 volumiokoupelna volumio[949]: info: Reading ALSA contributions from plugins. Aug 28 14:34:06 volumiokoupelna volumio[949]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:34:06 volumiokoupelna volumio[949]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:34:06 volumiokoupelna volumio[949]: info: Retrieving Cloud Streaming UI Aug 28 14:34:06 volumiokoupelna volumio[949]: info: MRS: Removed streaming files Aug 28 14:34:06 volumiokoupelna volumio[949]: info: MRS: volumioStreaming STOPPED Aug 28 14:34:06 volumiokoupelna volumio[949]: info: MRS: SNAPSERVER STOPPED Aug 28 14:34:06 volumiokoupelna volumio[949]: info: MRS: SNAPCLIENT STOPPED Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Successfully Updated MyVolumio device Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Getting Tidal Cloud Configuration Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Getting Qobuz Cloud Configuration Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Asking plugin for UI Config Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Getting Spotify Cloud Configuration Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Asking plugin for UI Config Aug 28 14:34:07 volumiokoupelna volumio[949]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Saving Spotify Acccount Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Got it Aug 28 14:34:07 volumiokoupelna volumio[949]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Successfully Updated MyVolumio device Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:34:07 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:08 volumiokoupelna sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 28 14:34:08 volumiokoupelna sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:08 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:08 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:08 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output for this device Aug 28 14:34:08 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Adding audio output: Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Adding audio output: Aug 28 14:34:08 volumiokoupelna 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. Aug 28 14:34:08 volumiokoupelna 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. Aug 28 14:34:08 volumiokoupelna systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 28 14:34:08 volumiokoupelna sudo[1672]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:08 volumiokoupelna autossh[1691]: port set to 0, monitoring disabled Aug 28 14:34:08 volumiokoupelna autossh[1691]: starting ssh (count 1) Aug 28 14:34:08 volumiokoupelna autossh[1691]: ssh child pid is 1694 Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:08 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:08 volumiokoupelna volumiossh-tunnel[1694]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Aug 28 14:34:09 volumiokoupelna volumio[949]: info: Got Tidal Cloud Configuration Aug 28 14:34:09 volumiokoupelna volumio[949]: info: Got it Aug 28 14:34:09 volumiokoupelna volumio[949]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:34:09 volumiokoupelna volumio[949]: info: Remote SSH Started Aug 28 14:34:10 volumiokoupelna volumio[949]: info: Asound.conf file written Aug 28 14:34:11 volumiokoupelna sudo[1699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 28 14:34:11 volumiokoupelna sudo[1699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:11 volumiokoupelna sudo[1699]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card b1 Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: /usr/share/alsa/init/bcm2835:14: control element not found Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card b1 Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card Headphones Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: /usr/share/alsa/init/bcm2835:20: control element not found Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card Headphones Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card vc4hdmi Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 28 14:34:11 volumiokoupelna volumio[949]: Hardware is initialized using a generic method Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card vc4hdmi Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card sndrpihifiberry Aug 28 14:34:11 volumiokoupelna volumio[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 28 14:34:11 volumiokoupelna volumio[949]: Found hardware: "RPi-simple" "" "" "" "" Aug 28 14:34:11 volumiokoupelna volumio[949]: Hardware is initialized using a generic method Aug 28 14:34:11 volumiokoupelna volumio[949]: No state is present for card sndrpihifiberry Aug 28 14:34:11 volumiokoupelna volumio[949]: info: Output device has changed, restarting MPD Aug 28 14:34:11 volumiokoupelna volumio[949]: info: Output device has changed, restarting Shairport Sync Aug 28 14:34:11 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:11 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:34:11 volumiokoupelna sudo[1705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 14:34:11 volumiokoupelna sudo[1705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:11 volumiokoupelna sudo[1705]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:11 volumiokoupelna sudo[1707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 14:34:11 volumiokoupelna volumio[949]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 14:34:11 volumiokoupelna sudo[1707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:11 volumiokoupelna volumio[949]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 28 14:34:11 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:11 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 28 14:34:11 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [FUNC] onStart Aug 28 14:34:11 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Aug 28 14:34:11 volumiokoupelna systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 28 14:34:11 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Aug 28 14:34:11 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Aug 28 14:34:11 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Aug 28 14:34:11 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 28 14:34:11 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 28 14:34:12 volumiokoupelna volumio[949]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 28 14:34:12 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:12 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding METAVOLUMIO REST API Endpoints Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 28 14:34:12 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 28 14:34:12 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Preparing CD Folders Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding CD REST API Endpoints Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Starting UDEV Watcher for CD Aug 28 14:34:12 volumiokoupelna volumio[949]: info: Detecting CD presence with UDEV Aug 28 14:34:12 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 28 14:34:12 volumiokoupelna systemd[1]: mpd.service: Deactivated successfully. Aug 28 14:34:12 volumiokoupelna systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 14:34:12 volumiokoupelna systemd[1]: mpd.service: Consumed 9.599s CPU time. Aug 28 14:34:12 volumiokoupelna systemd[1]: mpd.socket: Deactivated successfully. Aug 28 14:34:12 volumiokoupelna systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 14:34:12 volumiokoupelna systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 14:34:12 volumiokoupelna systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 14:34:12 volumiokoupelna systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 14:34:12 volumiokoupelna sudo[1727]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 14:34:12 volumiokoupelna sudo[1727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 14:34:12 volumiokoupelna sudo[1727]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:17 volumiokoupelna mpd[1729]: 2026-08-28T14:34:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 14:34:17 volumiokoupelna systemd[1]: Started mpd.service - Music Player Daemon. Aug 28 14:34:17 volumiokoupelna sudo[1707]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:17 volumiokoupelna volumio[949]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 28 14:34:17 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Adding inputs REST Endpoints Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Scanning Audio Inputs Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Checking against Known Cards name Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Adding Server instance for streaming Aug 28 14:34:17 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 28 14:34:17 volumiokoupelna volumio[949]: error: Hi Res Audio Failed Login: Missing Login Data Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 28 14:34:17 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 28 14:34:17 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 28 14:34:17 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 28 14:34:17 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 28 14:34:17 volumiokoupelna volumio[949]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 28 14:34:17 volumiokoupelna volumio[949]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 28 14:34:17 volumiokoupelna volumio[949]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 28 14:34:18 volumiokoupelna volumio[949]: info: Adding TIDAL REST API Endpoints Aug 28 14:34:18 volumiokoupelna sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 14:34:18 volumiokoupelna volumio[949]: info: Stopping AccessToken refresher cron for QOBUZ Aug 28 14:34:18 volumiokoupelna sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:18 volumiokoupelna sudo[1744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 28 14:34:18 volumiokoupelna sudo[1744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:18 volumiokoupelna sudo[1738]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:18 volumiokoupelna sudo[1744]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:18 volumiokoupelna volumio[949]: info: AccessToken refresher cron started for QOBUZ Aug 28 14:34:18 volumiokoupelna volumio[949]: info: Adding QOBUZ REST API Endpoints Aug 28 14:34:18 volumiokoupelna volumio[949]: info: MRS: Getting audio outputs on start Aug 28 14:34:18 volumiokoupelna volumio[949]: info: MRS: Requesting all other devices output Aug 28 14:34:18 volumiokoupelna sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 28 14:34:18 volumiokoupelna sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:18 volumiokoupelna systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 28 14:34:18 volumiokoupelna sudo[1748]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:19 volumiokoupelna volumio[949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 28 14:34:19 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Aug 28 14:34:19 volumiokoupelna volumio[949]: info: MPD Permissions set Aug 28 14:34:19 volumiokoupelna sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 28 14:34:19 volumiokoupelna sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:19 volumiokoupelna volumio[949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 28 14:34:19 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:19 volumiokoupelna sudo[1767]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:19 volumiokoupelna volumiobt[1771]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:19 volumiokoupelna sudo[1772]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:19 volumiokoupelna sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:19 volumiokoupelna sudo[1772]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:19 volumiokoupelna sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:19 volumiokoupelna sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:19 volumiokoupelna sudo[1775]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:19 volumiokoupelna volumiobt[1777]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:20 volumiokoupelna volumiobt[1780]: No default controller available Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:20 volumiokoupelna volumio[949]: error: updateQueue error: null Aug 28 14:34:20 volumiokoupelna volumio[949]: info: Executing endpoint qc_getconfig Aug 28 14:34:20 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 28 14:34:20 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:20.838 [1765.1765] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 28 14:34:20 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Aug 28 14:34:20 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.035 [1765.1765] INFO VolumeManager: [0x113af78]: Setting new playback volume: 75 Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.035 [1765.1765] INFO VolumeManager: [0x113af78]: Setting new mute state: 0 Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.035 [1765.1765] INFO AudioStreamManager: [0x113acd0]: Setting new audio download buffer size: 1048576 Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.035 [1765.1765] INFO QobuzConnect: [0x113b840]: Client initialized! Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.035 [1765.1765] INFO SampleApp: Starting Avahi advertising, name: Volumio_Koupelna, service name: _qobuz-connect._tcp Aug 28 14:34:21 volumiokoupelna volumio[949]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 28 14:34:21 volumiokoupelna volumio[949]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.087 [1765.1765] INFO LocalConfigManager: [0x113a9f8]: Starting Local Configuration server Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.087 [1765.1765] INFO SampleApp: Starting Local configuration server Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.088 [1765.1765] INFO SampleApp: Connected to UNIX socket client 0x1125818 Aug 28 14:34:21 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:21.088 [1765.1765] INFO SampleApp: Playback volume changed: 75 Aug 28 14:34:21 volumiokoupelna volumiobt[1794]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:21 volumiokoupelna volumiobt[1796]: [83B blob data] Aug 28 14:34:21 volumiokoupelna volumiobt[1796]: No default controller available Aug 28 14:34:21 volumiokoupelna volumiobt[1796]: [bluetoothctl]> pairable on Aug 28 14:34:21 volumiokoupelna volumiobt[1796]: No default controller available Aug 28 14:34:21 volumiokoupelna volumiobt[1796]: [113B blob data] Aug 28 14:34:21 volumiokoupelna volumiobt[1796]: [bluetoothctl]> Aug 28 14:34:21 volumiokoupelna volumiobt[1798]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:21 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:21 volumiokoupelna volumiobt[1800]: No agent is registered Aug 28 14:34:21 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:21 volumiokoupelna volumio[949]: info: Starting Shairport Sync Aug 28 14:34:21 volumiokoupelna volumiobt[1801]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:21 volumiokoupelna volumiobt[1802]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:21 volumiokoupelna sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 14:34:21 volumiokoupelna sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:21 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:21 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:21 volumiokoupelna systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 14:34:21 volumiokoupelna systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 14:34:21 volumiokoupelna systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 14:34:21 volumiokoupelna systemd[1]: shairport-sync.service: Consumed 2.409s CPU time. Aug 28 14:34:21 volumiokoupelna systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 14:34:21 volumiokoupelna volumio[949]: info: TidalConnect service stoped! Aug 28 14:34:21 volumiokoupelna sudo[1805]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:22 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Shairport-Sync Started Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:22 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: 2026-08-28 14:34:23 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: 2026-08-28 14:34:23 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: 2026-08-28 14:34:23 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: 2026-08-28 14:34:23 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: Traceback (most recent call last): Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: asyncio.run(_run()) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: return runner.run(main) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: return self._loop.run_until_complete(task) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: return future.result() Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: adapter_path = await find_adapter(bus) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: Exception: Bluetooth adapter not found Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: Traceback (most recent call last): Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: main() Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: asyncio.run(_run()) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: return runner.run(main) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: return self._loop.run_until_complete(task) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: return future.result() Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: adapter_path = await find_adapter(bus) Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:23 volumiokoupelna volumiobt[1803]: Exception: Bluetooth adapter not found Aug 28 14:34:23 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:23 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 28 14:34:23 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:23 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:23 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:23 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:23 volumiokoupelna systemd[1]: volumiobt.service: Consumed 2.280s CPU time. Aug 28 14:34:23 volumiokoupelna volumio[949]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 28 14:34:23 volumiokoupelna volumio[949]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 28 14:34:23 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:23 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:23 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 1. Aug 28 14:34:23 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:23 volumiokoupelna systemd[1]: volumiobt.service: Consumed 2.280s CPU time. Aug 28 14:34:23 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:23 volumiokoupelna volumiobt[1831]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:23 volumiokoupelna sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:23 volumiokoupelna sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:23 volumiokoupelna sudo[1832]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:24 volumiokoupelna sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:24 volumiokoupelna sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:24 volumiokoupelna sudo[1836]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:24 volumiokoupelna volumiobt[1838]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:24 volumiokoupelna volumiobt[1842]: No default controller available Aug 28 14:34:24 volumiokoupelna sudo[1834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 28 14:34:24 volumiokoupelna sudo[1834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:24 volumiokoupelna systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 14:34:24 volumiokoupelna sudo[1834]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:25 volumiokoupelna volumio[949]: info: Executing endpoint tc_getconfig Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 14:34:25 volumiokoupelna vtcs[1846]: STARTING TidalConnect services, version: 1.6.1 Aug 28 14:34:25 volumiokoupelna vtcs[1846]: STARTED TidalConnect services. Aug 28 14:34:25 volumiokoupelna volumio[949]: info: Executing endpoint tc_connect Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 28 14:34:25 volumiokoupelna volumio[949]: info: Connecting to TidalConnect Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::servicePushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:25 volumiokoupelna volumiobt[1858]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: 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} Aug 28 14:34:25 volumiokoupelna volumio[949]: verbose: CURRENT POSITION 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::syncState stateService stop Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::syncState currentStatus stop Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:25 volumiokoupelna volumiobt[1862]: [83B blob data] Aug 28 14:34:25 volumiokoupelna volumiobt[1862]: No default controller available Aug 28 14:34:25 volumiokoupelna volumiobt[1862]: [bluetoothctl]> pairable on Aug 28 14:34:25 volumiokoupelna volumiobt[1862]: No default controller available Aug 28 14:34:25 volumiokoupelna volumiobt[1862]: [bluetoothctl]> Aug 28 14:34:25 volumiokoupelna volumiobt[1864]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: No code Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::servicePushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:25 volumiokoupelna volumiobt[1866]: No agent is registered Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: 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} Aug 28 14:34:25 volumiokoupelna volumiobt[1867]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:25 volumiokoupelna volumiobt[1868]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:25 volumiokoupelna volumio[949]: verbose: CURRENT POSITION 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::syncState stateService stop Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::syncState currentStatus stop Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: No code Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:25 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:25 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: 2026-08-28 14:34:26 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: 2026-08-28 14:34:26 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: 2026-08-28 14:34:26 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: 2026-08-28 14:34:26 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: Traceback (most recent call last): Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: asyncio.run(_run()) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: return runner.run(main) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: return self._loop.run_until_complete(task) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: return future.result() Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: adapter_path = await find_adapter(bus) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: Exception: Bluetooth adapter not found Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: Traceback (most recent call last): Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: main() Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: asyncio.run(_run()) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: return runner.run(main) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: return self._loop.run_until_complete(task) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: return future.result() Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: adapter_path = await find_adapter(bus) Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:26 volumiokoupelna volumiobt[1869]: Exception: Bluetooth adapter not found Aug 28 14:34:26 volumiokoupelna volumio[949]: error: MyVolumio Plugins failed to start in a timely fashion Aug 28 14:34:26 volumiokoupelna volumio[949]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Aug 28 14:34:26 volumiokoupelna volumio[949]: [Metrics] CommandRouter: 107s 965.18ms Aug 28 14:34:26 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumiosetStartupVolume Aug 28 14:34:26 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 14:34:26 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:34:27 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:27 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:27 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.807s CPU time. Aug 28 14:34:27 volumiokoupelna volumio[949]: info: CoreCommandRouter::Close All Modals sent Aug 28 14:34:27 volumiokoupelna volumio[949]: info: CoreCommandRouter::Close All Modals sent Aug 28 14:34:27 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 2. Aug 28 14:34:27 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:27 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.807s CPU time. Aug 28 14:34:27 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:27 volumiokoupelna volumiobt[1877]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:27 volumiokoupelna sudo[1878]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:27 volumiokoupelna sudo[1878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:27 volumiokoupelna sudo[1878]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:27 volumiokoupelna sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:27 volumiokoupelna sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:27 volumiokoupelna sudo[1880]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:27 volumiokoupelna volumiobt[1882]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:27 volumiokoupelna volumiobt[1885]: No default controller available Aug 28 14:34:27 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:27 volumiokoupelna volumio[949]: info: TidalConnect service started! Aug 28 14:34:27 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:27 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:28 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 28 14:34:28 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 14:34:28 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:28 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:28 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:28 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:28 volumiokoupelna volumiobt[1896]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:28 volumiokoupelna volumiobt[1898]: [165B blob data] Aug 28 14:34:28 volumiokoupelna volumiobt[1898]: [bluetoothctl]> discoverable on Aug 28 14:34:28 volumiokoupelna volumiobt[1898]: No default controller available Aug 28 14:34:28 volumiokoupelna volumiobt[1898]: [bluetoothctl]> pairable on Aug 28 14:34:28 volumiokoupelna volumiobt[1898]: No default controller available Aug 28 14:34:28 volumiokoupelna volumiobt[1898]: [bluetoothctl]> Aug 28 14:34:28 volumiokoupelna volumiobt[1902]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:29 volumiokoupelna volumiobt[1905]: No agent is registered Aug 28 14:34:29 volumiokoupelna volumiobt[1911]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:29 volumiokoupelna volumiobt[1912]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: 2026-08-28 14:34:30 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: 2026-08-28 14:34:30 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: 2026-08-28 14:34:30 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: 2026-08-28 14:34:30 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: Traceback (most recent call last): Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: asyncio.run(_run()) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: return runner.run(main) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: return self._loop.run_until_complete(task) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: return future.result() Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: adapter_path = await find_adapter(bus) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: Exception: Bluetooth adapter not found Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: Traceback (most recent call last): Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: main() Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: asyncio.run(_run()) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: return runner.run(main) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: return self._loop.run_until_complete(task) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: return future.result() Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: adapter_path = await find_adapter(bus) Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:30 volumiokoupelna volumiobt[1913]: Exception: Bluetooth adapter not found Aug 28 14:34:30 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:30 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:30 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.675s CPU time. Aug 28 14:34:30 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 3. Aug 28 14:34:30 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:30 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.675s CPU time. Aug 28 14:34:30 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:30 volumiokoupelna volumiobt[1915]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:30 volumiokoupelna sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:30 volumiokoupelna sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:30 volumiokoupelna sudo[1916]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:30 volumiokoupelna sudo[1918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:30 volumiokoupelna sudo[1918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:30 volumiokoupelna sudo[1918]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:30 volumiokoupelna volumiobt[1920]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:30 volumiokoupelna volumiobt[1923]: No default controller available Aug 28 14:34:31 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:31 volumiokoupelna volumiobt[1925]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:31 volumiokoupelna volumiobt[1926]: [83B blob data] Aug 28 14:34:31 volumiokoupelna volumiobt[1926]: No default controller available Aug 28 14:34:31 volumiokoupelna volumiobt[1926]: [bluetoothctl]> pairable on Aug 28 14:34:31 volumiokoupelna volumiobt[1926]: No default controller available Aug 28 14:34:31 volumiokoupelna volumiobt[1926]: [113B blob data] Aug 28 14:34:32 volumiokoupelna volumiobt[1926]: [bluetoothctl]> Aug 28 14:34:32 volumiokoupelna volumiobt[1927]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:32 volumiokoupelna volumiobt[1929]: No agent is registered Aug 28 14:34:32 volumiokoupelna volumiobt[1930]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:32 volumiokoupelna volumiobt[1931]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: 2026-08-28 14:34:33 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: 2026-08-28 14:34:33 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: 2026-08-28 14:34:33 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: 2026-08-28 14:34:33 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: Traceback (most recent call last): Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: asyncio.run(_run()) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: return runner.run(main) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: return self._loop.run_until_complete(task) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: return future.result() Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: adapter_path = await find_adapter(bus) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: Exception: Bluetooth adapter not found Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: Traceback (most recent call last): Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: main() Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: asyncio.run(_run()) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: return runner.run(main) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: return self._loop.run_until_complete(task) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: return future.result() Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: adapter_path = await find_adapter(bus) Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:33 volumiokoupelna volumiobt[1932]: Exception: Bluetooth adapter not found Aug 28 14:34:33 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:33 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:33 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.655s CPU time. Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:33 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:33 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:33 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 4. Aug 28 14:34:33 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:33 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.655s CPU time. Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:33 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:33 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:33 volumiokoupelna volumiobt[1939]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:33 volumiokoupelna sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:33 volumiokoupelna sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:33 volumiokoupelna sudo[1940]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:33 volumiokoupelna sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:33 volumiokoupelna sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:34 volumiokoupelna sudo[1942]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:34 volumiokoupelna volumiobt[1944]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:34 volumiokoupelna volumio[949]: info: BOOT COMPLETED Aug 28 14:34:34 volumiokoupelna volumiobt[1947]: No default controller available Aug 28 14:34:35 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:35 volumiokoupelna volumiobt[1949]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:35 volumiokoupelna volumiobt[1950]: [83B blob data] Aug 28 14:34:35 volumiokoupelna volumiobt[1950]: No default controller available Aug 28 14:34:35 volumiokoupelna volumiobt[1950]: [bluetoothctl]> pairable on Aug 28 14:34:35 volumiokoupelna volumiobt[1950]: No default controller available Aug 28 14:34:35 volumiokoupelna volumiobt[1950]: [113B blob data] Aug 28 14:34:35 volumiokoupelna volumiobt[1950]: [bluetoothctl]> Aug 28 14:34:35 volumiokoupelna volumiobt[1951]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:35 volumiokoupelna volumiobt[1953]: No agent is registered Aug 28 14:34:35 volumiokoupelna volumiobt[1954]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:35 volumiokoupelna volumiobt[1955]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:35 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:35 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: 2026-08-28 14:34:36 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: 2026-08-28 14:34:36 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: 2026-08-28 14:34:36 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: 2026-08-28 14:34:36 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: Traceback (most recent call last): Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: asyncio.run(_run()) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: return runner.run(main) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: return self._loop.run_until_complete(task) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: return future.result() Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: adapter_path = await find_adapter(bus) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: Exception: Bluetooth adapter not found Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: Traceback (most recent call last): Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: main() Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: asyncio.run(_run()) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: return runner.run(main) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: return self._loop.run_until_complete(task) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: return future.result() Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: adapter_path = await find_adapter(bus) Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:36 volumiokoupelna volumiobt[1956]: Exception: Bluetooth adapter not found Aug 28 14:34:36 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:36 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:36 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.680s CPU time. Aug 28 14:34:36 volumiokoupelna volumio[949]: info: Disabling MyMusic plugin qobuzconnect Aug 28 14:34:36 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service qobuzconnect Aug 28 14:34:36 volumiokoupelna volumio[949]: info: QobuzConnect: setDeactiveState invoked Aug 28 14:34:36 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:36 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:36 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:36.880 [1765.1765] INFO MediaEngine: [0x113ac20]: Stopping playback, keeping tracks Aug 28 14:34:36 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 5. Aug 28 14:34:36 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:37 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.680s CPU time. Aug 28 14:34:37 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:37 volumiokoupelna sudo[1958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop qobuz-connect Aug 28 14:34:37 volumiokoupelna sudo[1958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:37 volumiokoupelna volumiobt[1961]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:37 volumiokoupelna sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:37 volumiokoupelna sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:37 volumiokoupelna systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 28 14:34:37 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:37.172 [1765.1765] INFO SampleApp: Stopping Local configuration server Aug 28 14:34:37 volumiokoupelna sudo[1962]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:37 volumiokoupelna sudo[1964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:37 volumiokoupelna sudo[1964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:37 volumiokoupelna sudo[1964]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:37 volumiokoupelna volumiobt[1966]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:37 volumiokoupelna volumiobt[1969]: No default controller available Aug 28 14:34:38 volumiokoupelna volumiobt[1971]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:38 volumiokoupelna volumiobt[1972]: [83B blob data] Aug 28 14:34:38 volumiokoupelna volumiobt[1972]: No default controller available Aug 28 14:34:38 volumiokoupelna volumiobt[1972]: [bluetoothctl]> pairable on Aug 28 14:34:38 volumiokoupelna volumiobt[1972]: No default controller available Aug 28 14:34:38 volumiokoupelna volumiobt[1972]: [113B blob data] Aug 28 14:34:38 volumiokoupelna volumiobt[1972]: [bluetoothctl]> Aug 28 14:34:38 volumiokoupelna volumiobt[1973]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:38 volumiokoupelna volumiobt[1975]: No agent is registered Aug 28 14:34:38 volumiokoupelna volumiobt[1976]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:38 volumiokoupelna volumiobt[1977]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:39 volumiokoupelna qobuz-connect[1765]: 20260828 14:34:39.122 [1765.1765] INFO SampleApp: shat down connection on UNIX socket Aug 28 14:34:39 volumiokoupelna systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 28 14:34:39 volumiokoupelna systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 28 14:34:39 volumiokoupelna sudo[1958]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:39 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:39 volumiokoupelna volumio[949]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 28 14:34:39 volumiokoupelna volumio[949]: info: QobuzConnect: setDeactiveState invoked Aug 28 14:34:39 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:39 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:39 volumiokoupelna volumio[949]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket disconnected and tore down Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Disabling plugin qobuzconnect Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: this is already registered, f01050ee-eb4e-4960-b519-834f5112b9c3 Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Terasa Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:39 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: 2026-08-28 14:34:40 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: 2026-08-28 14:34:40 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: 2026-08-28 14:34:40 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: 2026-08-28 14:34:40 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: Traceback (most recent call last): Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: asyncio.run(_run()) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: return runner.run(main) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: return self._loop.run_until_complete(task) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: return future.result() Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: adapter_path = await find_adapter(bus) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: Exception: Bluetooth adapter not found Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: Traceback (most recent call last): Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: main() Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: asyncio.run(_run()) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: return runner.run(main) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: return self._loop.run_until_complete(task) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: return future.result() Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: adapter_path = await find_adapter(bus) Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:40 volumiokoupelna volumiobt[1978]: Exception: Bluetooth adapter not found Aug 28 14:34:40 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:40 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:40 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.773s CPU time. Aug 28 14:34:40 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 6. Aug 28 14:34:40 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:40 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.773s CPU time. Aug 28 14:34:40 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:40 volumiokoupelna volumiobt[2000]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:40 volumiokoupelna sudo[2001]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:40 volumiokoupelna sudo[2001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:40 volumiokoupelna sudo[2001]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:40 volumiokoupelna sudo[2003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:40 volumiokoupelna sudo[2003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:40 volumiokoupelna sudo[2003]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:40 volumiokoupelna volumiobt[2005]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:40 volumiokoupelna volumiobt[2008]: No default controller available Aug 28 14:34:41 volumiokoupelna volumio[949]: info: Disabling MyMusic plugin tidalconnect Aug 28 14:34:41 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Aug 28 14:34:41 volumiokoupelna vtcs[1846]: [2026-08-28 14:34:41.209] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 28 14:34:41 volumiokoupelna vtcs[1846]: [2026-08-28 14:34:41.220] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Aug 28 14:34:41 volumiokoupelna vtcs[1846]: [2026-08-28 14:34:41.220] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 28 14:34:41 volumiokoupelna volumio[949]: info: Disabling plugin tidalconnect Aug 28 14:34:41 volumiokoupelna sudo[2011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 14:34:41 volumiokoupelna sudo[2011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:41 volumiokoupelna systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 28 14:34:41 volumiokoupelna systemd[1]: vtcs.service: Killing process 1851 (vtcs) with signal SIGKILL. Aug 28 14:34:41 volumiokoupelna systemd[1]: vtcs.service: Deactivated successfully. Aug 28 14:34:41 volumiokoupelna systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 14:34:41 volumiokoupelna sudo[2011]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:41 volumiokoupelna volumiobt[2015]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:42 volumiokoupelna volumiobt[2016]: [83B blob data] Aug 28 14:34:42 volumiokoupelna volumiobt[2016]: No default controller available Aug 28 14:34:42 volumiokoupelna volumiobt[2016]: [bluetoothctl]> pairable on Aug 28 14:34:42 volumiokoupelna volumiobt[2016]: No default controller available Aug 28 14:34:42 volumiokoupelna volumiobt[2016]: [113B blob data] Aug 28 14:34:42 volumiokoupelna volumiobt[2016]: [bluetoothctl]> Aug 28 14:34:42 volumiokoupelna volumiobt[2018]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:42 volumiokoupelna volumiobt[2020]: No agent is registered Aug 28 14:34:42 volumiokoupelna volumiobt[2021]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:42 volumiokoupelna volumiobt[2022]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:43 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: 2026-08-28 14:34:43 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: 2026-08-28 14:34:43 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: 2026-08-28 14:34:43 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: 2026-08-28 14:34:43 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: Traceback (most recent call last): Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: asyncio.run(_run()) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: return runner.run(main) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: return self._loop.run_until_complete(task) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: return future.result() Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: adapter_path = await find_adapter(bus) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: Exception: Bluetooth adapter not found Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: Traceback (most recent call last): Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: main() Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: asyncio.run(_run()) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: return runner.run(main) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: return self._loop.run_until_complete(task) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: return future.result() Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: adapter_path = await find_adapter(bus) Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:43 volumiokoupelna volumiobt[2023]: Exception: Bluetooth adapter not found Aug 28 14:34:43 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:43 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:43 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.660s CPU time. Aug 28 14:34:43 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 7. Aug 28 14:34:43 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:43 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.660s CPU time. Aug 28 14:34:43 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:43 volumiokoupelna volumiobt[2025]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:43 volumiokoupelna sudo[2026]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:43 volumiokoupelna sudo[2026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:43 volumiokoupelna sudo[2026]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:43 volumiokoupelna sudo[2028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:43 volumiokoupelna sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:43 volumiokoupelna sudo[2028]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:43 volumiokoupelna volumiobt[2030]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:44 volumiokoupelna volumiobt[2033]: No default controller available Aug 28 14:34:44 volumiokoupelna volumio[949]: info: TidalConnect service stoped! Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:45 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:45 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:45 volumiokoupelna volumiobt[2040]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:45 volumiokoupelna volumiobt[2041]: [165B blob data] Aug 28 14:34:45 volumiokoupelna volumiobt[2041]: [bluetoothctl]> discoverable on Aug 28 14:34:45 volumiokoupelna volumiobt[2041]: No default controller available Aug 28 14:34:45 volumiokoupelna volumiobt[2041]: [bluetoothctl]> pairable on Aug 28 14:34:45 volumiokoupelna volumiobt[2041]: No default controller available Aug 28 14:34:45 volumiokoupelna volumiobt[2041]: [bluetoothctl]> Aug 28 14:34:45 volumiokoupelna volumiobt[2042]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:45 volumiokoupelna volumiobt[2044]: No agent is registered Aug 28 14:34:45 volumiokoupelna volumiobt[2045]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:45 volumiokoupelna volumiobt[2046]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:45 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:45 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:34:45 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: 2026-08-28 14:34:46 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: 2026-08-28 14:34:46 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: 2026-08-28 14:34:46 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: 2026-08-28 14:34:46 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: Traceback (most recent call last): Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: asyncio.run(_run()) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: return runner.run(main) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: return self._loop.run_until_complete(task) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: return future.result() Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: adapter_path = await find_adapter(bus) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: Exception: Bluetooth adapter not found Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: Traceback (most recent call last): Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: main() Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: asyncio.run(_run()) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: return runner.run(main) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: return self._loop.run_until_complete(task) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: return future.result() Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: adapter_path = await find_adapter(bus) Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:46 volumiokoupelna volumiobt[2047]: Exception: Bluetooth adapter not found Aug 28 14:34:46 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:46 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:46 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.661s CPU time. Aug 28 14:34:46 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 8. Aug 28 14:34:47 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:47 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:47 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.661s CPU time. Aug 28 14:34:47 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:47 volumiokoupelna volumiobt[2049]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:47 volumiokoupelna sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:47 volumiokoupelna sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:47 volumiokoupelna sudo[2050]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:47 volumiokoupelna sudo[2052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:47 volumiokoupelna sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:47 volumiokoupelna sudo[2052]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:47 volumiokoupelna volumiobt[2054]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:47 volumiokoupelna volumiobt[2057]: No default controller available Aug 28 14:34:48 volumiokoupelna volumiobt[2060]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:48 volumiokoupelna volumiobt[2061]: [83B blob data] Aug 28 14:34:48 volumiokoupelna volumiobt[2061]: No default controller available Aug 28 14:34:48 volumiokoupelna volumiobt[2061]: [bluetoothctl]> pairable on Aug 28 14:34:48 volumiokoupelna volumiobt[2061]: No default controller available Aug 28 14:34:48 volumiokoupelna volumiobt[2061]: [113B blob data] Aug 28 14:34:48 volumiokoupelna volumiobt[2061]: [bluetoothctl]> Aug 28 14:34:48 volumiokoupelna volumiobt[2062]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:48 volumiokoupelna volumiobt[2064]: No agent is registered Aug 28 14:34:48 volumiokoupelna volumiobt[2065]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:48 volumiokoupelna volumiobt[2066]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: 2026-08-28 14:34:49 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: 2026-08-28 14:34:49 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: 2026-08-28 14:34:49 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: 2026-08-28 14:34:49 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: Traceback (most recent call last): Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: asyncio.run(_run()) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: return runner.run(main) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: return self._loop.run_until_complete(task) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: return future.result() Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: adapter_path = await find_adapter(bus) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: Exception: Bluetooth adapter not found Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: Traceback (most recent call last): Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: main() Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: asyncio.run(_run()) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: return runner.run(main) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: return self._loop.run_until_complete(task) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: return future.result() Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: adapter_path = await find_adapter(bus) Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:49 volumiokoupelna volumiobt[2067]: Exception: Bluetooth adapter not found Aug 28 14:34:50 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:50 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:50 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.693s CPU time. Aug 28 14:34:50 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 9. Aug 28 14:34:50 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:50 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.693s CPU time. Aug 28 14:34:50 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:50 volumiokoupelna volumiobt[2088]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:50 volumiokoupelna sudo[2089]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:50 volumiokoupelna sudo[2089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:50 volumiokoupelna sudo[2089]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:50 volumiokoupelna sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:50 volumiokoupelna sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:50 volumiokoupelna sudo[2092]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:50 volumiokoupelna volumiobt[2094]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:50 volumiokoupelna volumiobt[2097]: No default controller available Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:50 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:50 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:50 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:51 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:51 volumiokoupelna volumiobt[2099]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:51 volumiokoupelna volumiobt[2100]: [83B blob data] Aug 28 14:34:51 volumiokoupelna volumiobt[2100]: No default controller available Aug 28 14:34:51 volumiokoupelna volumiobt[2100]: [bluetoothctl]> pairable on Aug 28 14:34:51 volumiokoupelna volumiobt[2100]: No default controller available Aug 28 14:34:51 volumiokoupelna volumiobt[2100]: [bluetoothctl]> Aug 28 14:34:51 volumiokoupelna volumiobt[2101]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:51 volumiokoupelna volumiobt[2103]: No agent is registered Aug 28 14:34:51 volumiokoupelna volumiobt[2104]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:51 volumiokoupelna volumiobt[2105]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: 2026-08-28 14:34:53 a2dp-agent [INFO] Connecting to system D-Bus Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: 2026-08-28 14:34:53 a2dp-agent [INFO] Connected to system D-Bus Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: 2026-08-28 14:34:53 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: 2026-08-28 14:34:53 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: Traceback (most recent call last): Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: asyncio.run(_run()) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: return runner.run(main) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: return self._loop.run_until_complete(task) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: return future.result() Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: adapter_path = await find_adapter(bus) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: Exception: Bluetooth adapter not found Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: Traceback (most recent call last): Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/bin/bt/a2dp-agent", line 234, in Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: main() Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/bin/bt/a2dp-agent", line 225, in main Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: asyncio.run(_run()) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: return runner.run(main) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: return self._loop.run_until_complete(task) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: return future.result() Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/bin/bt/a2dp-agent", line 175, in _run Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: adapter_path = await find_adapter(bus) Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: ^^^^^^^^^^^^^^^^^^^^^^^ Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: raise Exception("Bluetooth adapter not found") Aug 28 14:34:53 volumiokoupelna volumiobt[2106]: Exception: Bluetooth adapter not found Aug 28 14:34:53 volumiokoupelna systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:34:53 volumiokoupelna systemd[1]: volumiobt.service: Failed with result 'exit-code'. Aug 28 14:34:53 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.681s CPU time. Aug 28 14:34:53 volumiokoupelna systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 10. Aug 28 14:34:53 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:53 volumiokoupelna systemd[1]: volumiobt.service: Consumed 1.681s CPU time. Aug 28 14:34:53 volumiokoupelna systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:53 volumiokoupelna volumiobt[2108]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 28 14:34:53 volumiokoupelna sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 28 14:34:53 volumiokoupelna sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:53 volumiokoupelna sudo[2109]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:53 volumiokoupelna sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 28 14:34:53 volumiokoupelna sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:53 volumiokoupelna sudo[2111]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:53 volumiokoupelna volumiobt[2113]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 28 14:34:53 volumiokoupelna volumiobt[2116]: No default controller available Aug 28 14:34:54 volumiokoupelna volumio[949]: info: Disabling MyMusic plugin bluetooth Aug 28 14:34:54 volumiokoupelna volumio[949]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Aug 28 14:34:54 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [FUNC] onStop Aug 28 14:34:54 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Aug 28 14:34:54 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Aug 28 14:34:54 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Aug 28 14:34:54 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Aug 28 14:34:54 volumiokoupelna sudo[2119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Aug 28 14:34:54 volumiokoupelna sudo[2119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:34:54 volumiokoupelna systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Aug 28 14:34:54 volumiokoupelna volumiobt[2122]: INFO [BTSTART] Disconnecting all Bluetooth devices... Aug 28 14:34:54 volumiokoupelna volumiobt[2127]: INFO [BTSTART] Unregistering Bluetooth agent... Aug 28 14:34:54 volumiokoupelna volumiobt[2129]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 28 14:34:54 volumiokoupelna volumiobt[2128]: No agent is registered Aug 28 14:34:55 volumiokoupelna volumiobt[2131]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Aug 28 14:34:55 volumiokoupelna volumiobt[2130]: [83B blob data] Aug 28 14:34:55 volumiokoupelna volumiobt[2130]: No default controller available Aug 28 14:34:55 volumiokoupelna volumiobt[2130]: [bluetoothctl]> pairable on Aug 28 14:34:55 volumiokoupelna volumiobt[2130]: No default controller available Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:34:55 volumiokoupelna volumiobt[2134]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Aug 28 14:34:55 volumiokoupelna volumiobt[2130]: [bluetoothctl]> Aug 28 14:34:55 volumiokoupelna volumiobt[2133]: INFO [BTSTART] Registering Bluetooth agent... Aug 28 14:34:55 volumiokoupelna volumiobt[2137]: No agent is registered Aug 28 14:34:55 volumiokoupelna volumiobt[2138]: INFO [BTSTART] Agent registered successfully. Aug 28 14:34:55 volumiokoupelna volumiobt[2139]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 28 14:34:55 volumiokoupelna systemd[1]: volumiobt.service: Deactivated successfully. Aug 28 14:34:55 volumiokoupelna systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 28 14:34:55 volumiokoupelna sudo[2119]: pam_unix(sudo:session): session closed for user root Aug 28 14:34:55 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Aug 28 14:34:55 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreStateMachine::resetVolumioState Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreStateMachine::getcurrentVolume Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 14:34:55 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioStop Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreStateMachine::stop Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 14:34:55 volumiokoupelna volumio[949]: Unable to parse: Aug 28 14:34:55 volumiokoupelna volumio[949]: Simple mixer control 'Master',0 Aug 28 14:34:55 volumiokoupelna volumio[949]: Capabilities: volume volume-joined Aug 28 14:34:55 volumiokoupelna volumio[949]: Playback channels: Mono Aug 28 14:34:55 volumiokoupelna volumio[949]: Capture channels: Mono Aug 28 14:34:55 volumiokoupelna volumio[949]: Limits: 0 - 248 Aug 28 14:34:55 volumiokoupelna volumio[949]: Mono: 248 [100%] Aug 28 14:34:55 volumiokoupelna volumio[949]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 28 14:34:55 volumiokoupelna volumio[949]: ------------------------------------ BT MESSAGE: Failed to power off adapter: Aug 28 14:34:55 volumiokoupelna volumio[949]: info: Disabling plugin bluetooth Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:55 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:56 volumiokoupelna volumio[949]: Unable to parse: Aug 28 14:34:56 volumiokoupelna volumio[949]: Simple mixer control 'Master',0 Aug 28 14:34:56 volumiokoupelna volumio[949]: Capabilities: volume volume-joined Aug 28 14:34:56 volumiokoupelna volumio[949]: Playback channels: Mono Aug 28 14:34:56 volumiokoupelna volumio[949]: Capture channels: Mono Aug 28 14:34:56 volumiokoupelna volumio[949]: Limits: 0 - 248 Aug 28 14:34:56 volumiokoupelna volumio[949]: Mono: 248 [100%] Aug 28 14:34:56 volumiokoupelna volumio[949]: info: VolumeController:: Volume=undefined Mute =false Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:56 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:56 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreStateMachine::updateTrackBlock Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrackBlock Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 14:34:56 volumiokoupelna volumio[949]: Unable to parse: Aug 28 14:34:56 volumiokoupelna volumio[949]: Simple mixer control 'Master',0 Aug 28 14:34:56 volumiokoupelna volumio[949]: Capabilities: volume volume-joined Aug 28 14:34:56 volumiokoupelna volumio[949]: Playback channels: Mono Aug 28 14:34:56 volumiokoupelna volumio[949]: Capture channels: Mono Aug 28 14:34:56 volumiokoupelna volumio[949]: Limits: 0 - 248 Aug 28 14:34:56 volumiokoupelna volumio[949]: Mono: 248 [100%] Aug 28 14:34:56 volumiokoupelna volumio[949]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 28 14:34:56 volumiokoupelna volumio[949]: Unable to parse: Aug 28 14:34:56 volumiokoupelna volumio[949]: Simple mixer control 'Master',0 Aug 28 14:34:56 volumiokoupelna volumio[949]: Capabilities: volume volume-joined Aug 28 14:34:56 volumiokoupelna volumio[949]: Playback channels: Mono Aug 28 14:34:56 volumiokoupelna volumio[949]: Capture channels: Mono Aug 28 14:34:56 volumiokoupelna volumio[949]: Limits: 0 - 248 Aug 28 14:34:56 volumiokoupelna volumio[949]: Mono: 248 [100%] Aug 28 14:34:56 volumiokoupelna volumio[949]: info: VolumeController:: Volume=undefined Mute =false Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreStateMachine::pushState Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioPushState Aug 28 14:34:56 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output update for this device Aug 28 14:34:56 volumiokoupelna volumio[949]: info: MRS: Pushing multiroomSync output Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:56 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:34:57 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:34:57 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:34:57 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:34:59 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: this is already registered, bc543e02-ad69-47b5-888b-11322360554a Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Loznice Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: this is already registered, f01050ee-eb4e-4960-b519-834f5112b9c3 Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Terasa Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:35:02 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:35:03 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:35:05 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:35:05 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:35:05 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:35:05 volumiokoupelna volumio[949]: info: Listing playlists Aug 28 14:35:07 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:35:08 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:35:08 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: this is already registered, 349d0bfd-28d7-495f-a77d-a0cc00418021 Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Found device Volumio_Koupelna Aug 28 14:35:08 volumiokoupelna volumio[949]: info: CoreCommandRouter::volumioGetState Aug 28 14:35:08 volumiokoupelna volumio[949]: info: CorePlayQueue::getTrack 0 Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Restarting Browsing Aug 28 14:35:08 volumiokoupelna volumio[949]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 14:35:09 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 28 14:35:09 volumiokoupelna volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 28 14:35:10 volumiokoupelna volumio[949]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 14:35:10 volumiokoupelna volumio[949]: Error: getaddrinfo -3007 Aug 28 14:35:10 volumiokoupelna volumio[949]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 28 14:35:10 volumiokoupelna volumio[949]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 28 14:35:10 volumiokoupelna volumio[949]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 28 14:35:10 volumiokoupelna volumio[949]: code: -3007, Aug 28 14:35:10 volumiokoupelna volumio[949]: errno: -3007, Aug 28 14:35:10 volumiokoupelna volumio[949]: syscall: 'getaddrinfo' Aug 28 14:35:10 volumiokoupelna volumio[949]: } Aug 28 14:35:10 volumiokoupelna volumio[949]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 14:35:16 volumiokoupelna sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 14:34' Aug 28 14:35:16 volumiokoupelna sudo[2218]: 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"