Oct 14 16:30:00 volumio volumio[9135]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 14 16:30:00 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 14 16:30:00 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:00 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:00 volumio volumio[9135]: info: Starting MyVolumio Remote Streaming Endpoints Oct 14 16:30:00 volumio volumio[9135]: info: MyVolumio not started Oct 14 16:30:00 volumio volumio[9135]: info: Initializing device activation check Oct 14 16:30:00 volumio volumio[9135]: info: Enabling MyVolumio plan premium via Activation Code and Device Method - Enable: 718192b0c9409ccf38311c5aff984608 Oct 14 16:30:00 volumio volumio[9135]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 14 16:30:00 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 14 16:30:02 volumio volumio[9135]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 14 16:30:02 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 14 16:30:02 volumio volumio[9135]: info: Streaming services startup Oct 14 16:30:02 volumio volumio[9135]: info: Starting Streaming Daemon Oct 14 16:30:02 volumio sudo[9408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 14 16:30:02 volumio sudo[9408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:02 volumio volumio[9135]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 14 16:30:02 volumio volumio[9135]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 14 16:30:02 volumio volumio[9135]: info: [MyVolumio PluginManager] No plugins found for user plan free Oct 14 16:30:02 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 14 16:30:02 volumio sudo[9408]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:02 volumio volumio[9135]: info: MyVolumio status changed Oct 14 16:30:02 volumio volumio[9135]: info: Streaming services startup Oct 14 16:30:02 volumio volumio[9135]: info: Starting Streaming Daemon Oct 14 16:30:02 volumio sudo[9415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 14 16:30:02 volumio volumio[9135]: error: Cannot start Volumio Streaming Daemon Oct 14 16:30:02 volumio sudo[9415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:02 volumio volumio[9135]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 14 16:30:02 volumio volumio[9135]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 14 16:30:02 volumio volumio[9135]: info: Remote config written successfully Oct 14 16:30:02 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 14 16:30:02 volumio sudo[9415]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:02 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 14 16:30:02 volumio volumio[9135]: info: [1791966602971] Starting BluetoothController Oct 14 16:30:03 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 14 16:30:06 volumio sudo[9419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 14 16:30:06 volumio sudo[9419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:06 volumio sudo[9419]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:06 volumio volumio[9135]: info: MRS: MultiRoom plugin initialized Oct 14 16:30:06 volumio volumio[9135]: info: MRS: STOPPING SNAPCLIENT Oct 14 16:30:06 volumio volumio[9135]: info: MRS: Snap server stop Oct 14 16:30:06 volumio volumio[9135]: info: MRS: STOPPING volumioStreaming Oct 14 16:30:06 volumio sudo[9436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 14 16:30:06 volumio sudo[9436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 14 16:30:06 volumio sudo[9438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 14 16:30:06 volumio sudo[9438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:06 volumio sudo[9440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 14 16:30:06 volumio sudo[9440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:06 volumio sudo[9443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 14 16:30:06 volumio sudo[9443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:06 volumio sudo[9443]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 14 16:30:06 volumio volumio[9135]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/smart_inputs/index.js Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 14 16:30:06 volumio volumio[9135]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/hi_res_audio/index.js Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 14 16:30:06 volumio volumio[9135]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/tidal/index.js Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 14 16:30:06 volumio volumio[9135]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/qobuz/index.js Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 14 16:30:06 volumio volumio[9135]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/tidalconnect/index.js Oct 14 16:30:06 volumio volumio[9135]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 14 16:30:06 volumio sudo[9436]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:06 volumio volumio[9135]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/qobuzconnect/index.js Oct 14 16:30:06 volumio volumio[9135]: info: Preparing to generate the ALSA configuration file Oct 14 16:30:06 volumio sudo[9438]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:06 volumio sudo[9440]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:07 volumio volumio[9135]: info: MRS: Removed streaming files Oct 14 16:30:07 volumio volumio[9135]: info: MRS: volumioStreaming STOPPED Oct 14 16:30:07 volumio volumio[9135]: info: MRS: SNAPSERVER STOPPED Oct 14 16:30:07 volumio volumio[9135]: info: MRS: SNAPCLIENT STOPPED Oct 14 16:30:07 volumio volumio[9135]: error: Cannot start Volumio Streaming Daemon Oct 14 16:30:07 volumio volumio[9135]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 14 16:30:07 volumio volumio[9135]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 14 16:30:07 volumio volumio[9135]: info: Asound.conf file unchanged, so no further update is needed Oct 14 16:30:07 volumio volumio[9135]: info: Output device has changed, restarting MPD Oct 14 16:30:07 volumio volumio[9135]: info: Output device has changed, restarting Shairport Sync Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:07 volumio sudo[9463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 16:30:07 volumio sudo[9463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:07 volumio sudo[9463]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:07 volumio sudo[9465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 16:30:07 volumio volumio[9135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 16:30:07 volumio sudo[9465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:07 volumio volumio[9135]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 14 16:30:07 volumio volumio[9135]: info: MRS: Pushing multiroomSync output for this device Oct 14 16:30:07 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:07 volumio volumio[9135]: info: Adding audio output: Oct 14 16:30:07 volumio volumio[9135]: info: Adding audio output: Oct 14 16:30:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 16:30:07 volumio sudo[9475]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/hciconfig hci0 name Volumio Oct 14 16:30:07 volumio sudo[9475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:07 volumio sudo[9475]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:07 volumio volumio[9135]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 14 16:30:07 volumio volumio[9135]: info: Adding METAVOLUMIO REST API Endpoints Oct 14 16:30:07 volumio volumio[9135]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 14 16:30:07 volumio volumio[9135]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 14 16:30:07 volumio volumio[9135]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 14 16:30:07 volumio volumio[9135]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 14 16:30:07 volumio volumio[9135]: info: Preparing CD Folders Oct 14 16:30:07 volumio volumio[9135]: info: Adding CD REST API Endpoints Oct 14 16:30:07 volumio volumio[9135]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 14 16:30:07 volumio volumio[9135]: info: Starting UDEV Watcher for CD Oct 14 16:30:07 volumio volumio[9135]: info: Detecting CD presence with UDEV Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 14 16:30:07 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 16:30:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 16:30:07 volumio systemd[1]: mpd.service: Consumed 6.519s CPU time. Oct 14 16:30:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 16:30:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 16:30:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 14 16:30:07 volumio volumio[9135]: info: Adding inputs REST Endpoints Oct 14 16:30:07 volumio volumio[9135]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 14 16:30:07 volumio volumio[9135]: info: Scanning Audio Inputs Oct 14 16:30:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 16:30:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 16:30:07 volumio volumio[9135]: info: Checking against Known Cards name Oct 14 16:30:07 volumio volumio[9135]: info: Adding Server instance for streaming Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 14 16:30:07 volumio volumio[9135]: error: Hi Res Audio Failed Login: Missing Login Data Oct 14 16:30:07 volumio volumio[9135]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 14 16:30:07 volumio volumio[9135]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 14 16:30:07 volumio volumio[9135]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 14 16:30:07 volumio volumio[9135]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 14 16:30:07 volumio volumio[9135]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 14 16:30:07 volumio sudo[9486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 16:30:07 volumio sudo[9486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:07 volumio volumio[9135]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 14 16:30:07 volumio volumio[9135]: info: Adding TIDAL REST API Endpoints Oct 14 16:30:07 volumio volumio[9135]: info: Stopping AccessToken refresher cron for QOBUZ Oct 14 16:30:07 volumio sudo[9493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 16:30:07 volumio sudo[9493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:07 volumio volumio[9135]: info: AccessToken refresher cron started for QOBUZ Oct 14 16:30:07 volumio volumio[9135]: info: Adding QOBUZ REST API Endpoints Oct 14 16:30:07 volumio sudo[9486]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:07 volumio sudo[9493]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:07 volumio volumio[9135]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio' Oct 14 16:30:07 volumio volumio[9135]: Can't change local name on hci0: Network is down (100) Oct 14 16:30:07 volumio volumio[9135]: info: MPD Permissions set Oct 14 16:30:07 volumio sudo[9496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 16:30:07 volumio sudo[9496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:07 volumio volumio[9135]: info: Starting Shairport Sync Oct 14 16:30:07 volumio sudo[9484]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 16:30:07 volumio sudo[9484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 16:30:07 volumio sudo[9484]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:07 volumio volumio[9135]: info: Preparing to generate the ALSA configuration file Oct 14 16:30:07 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 16:30:07 volumio sudo[9496]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:08 volumio sudo[9507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 16:30:08 volumio sudo[9507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:08 volumio volumio[9135]: info: Asound.conf file unchanged, so no further update is needed Oct 14 16:30:08 volumio volumio[9135]: info: Output device has changed, restarting MPD Oct 14 16:30:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 16:30:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 16:30:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 16:30:08 volumio systemd[1]: shairport-sync.service: Consumed 1.802s CPU time. Oct 14 16:30:08 volumio volumio[9135]: info: Output device has changed, restarting Shairport Sync Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:08 volumio sudo[9511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 16:30:08 volumio sudo[9511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 16:30:08 volumio sudo[9511]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:08 volumio sudo[9507]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:08 volumio sudo[9513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 16:30:08 volumio sudo[9513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:08 volumio volumio[9135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 16:30:08 volumio volumio[9135]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: QobuzConnect: setDeactiveState invoked Oct 14 16:30:08 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:08 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:08 volumio volumio[9135]: info: Preparing to generate the ALSA configuration file Oct 14 16:30:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 16:30:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 16:30:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 16:30:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 16:30:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 16:30:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 16:30:08 volumio volumio[9135]: info: MPD Permissions set Oct 14 16:30:08 volumio volumio[9135]: info: Shairport-Sync Started Oct 14 16:30:08 volumio sudo[9542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 16:30:08 volumio sudo[9542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:08 volumio sudo[9542]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:08 volumio sudo[9546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 16:30:08 volumio sudo[9546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:08 volumio volumio[9135]: info: Executing endpoint qc_getconfig Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 16:30:08 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 16:30:08 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 16:30:08 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 16:30:08 volumio sudo[9546]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: Asound.conf file unchanged, so no further update is needed Oct 14 16:30:08 volumio volumio[9135]: info: Output device has changed, restarting MPD Oct 14 16:30:08 volumio sudo[9543]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 16:30:08 volumio sudo[9543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 16:30:08 volumio sudo[9543]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:08 volumio volumio[9135]: info: Output device has changed, restarting Shairport Sync Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:08 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:09 volumio sudo[9561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 16:30:09 volumio sudo[9561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:09 volumio sudo[9560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 16:30:09 volumio sudo[9560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:09 volumio volumio[9135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 16:30:09 volumio sudo[9560]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:09 volumio volumio[9135]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: QobuzConnect: setDeactiveState invoked Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:09 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:09 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 16:30:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 16:30:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 16:30:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 16:30:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 16:30:09 volumio sudo[9576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 16:30:09 volumio sudo[9576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 16:30:09 volumio volumio[9135]: info: MPD Permissions set Oct 14 16:30:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 16:30:09 volumio volumio[9135]: info: Executing endpoint qc_getconfig Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 16:30:09 volumio sudo[9576]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:09 volumio volumio[9135]: info: Starting Shairport Sync Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.347 [9551.9551] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 16:30:09 volumio volumio[9135]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 16:30:09 volumio volumio[9135]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio sudo[9583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 16:30:09 volumio sudo[9583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:09 volumio sudo[9585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 16:30:09 volumio sudo[9585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:09 volumio volumio[9135]: info: Starting Shairport Sync Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.607 [9551.9551] INFO VolumeManager: [0x9a3af8]: Setting new playback volume: 75 Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.608 [9551.9551] INFO VolumeManager: [0x9a3af8]: Setting new mute state: 0 Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.609 [9551.9551] INFO AudioStreamManager: [0x9a3850]: Setting new audio download buffer size: 1048576 Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.609 [9551.9551] INFO QobuzConnect: [0x9a43c0]: Client initialized! Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.610 [9551.9551] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 14 16:30:09 volumio sudo[9594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 16:30:09 volumio sudo[9594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.685 [9551.9551] INFO LocalConfigManager: [0x9a3578]: Starting Local Configuration server Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.685 [9551.9551] INFO SampleApp: Starting Local configuration server Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.686 [9551.9551] INFO SampleApp: Connected to UNIX socket client 0x98e818 Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.686 [9551.9551] INFO SampleApp: Playback volume changed: 75 Oct 14 16:30:09 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:09 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:09 volumio qobuz-connect[9551]: 20261014 16:30:09.711 [9551.9551] INFO SampleApp: Stopping Local configuration server Oct 14 16:30:09 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 16:30:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 16:30:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 16:30:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 16:30:09 volumio systemd[1]: shairport-sync.service: Consumed 1.284s CPU time. Oct 14 16:30:09 volumio sudo[9579]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 16:30:09 volumio sudo[9579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 16:30:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 16:30:09 volumio sudo[9585]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:09 volumio sudo[9594]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:09 volumio sudo[9579]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:09 volumio volumio[9135]: info: Shairport-Sync Started Oct 14 16:30:09 volumio volumio[9135]: info: Shairport-Sync Started Oct 14 16:30:10 volumio sudo[9616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 14 16:30:10 volumio sudo[9616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:10 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:10 volumio sudo[9616]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:10 volumio volumiobt[9619]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:10 volumio volumio[9135]: info: Volumio BT Module successfully started Oct 14 16:30:10 volumio sudo[9620]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:10 volumio sudo[9620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:10 volumio sudo[9620]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:10 volumio sudo[9622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:10 volumio sudo[9622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:10 volumio sudo[9622]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:10 volumio volumiobt[9624]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:10 volumio volumio[9135]: info: TidalConnect service stoped! Oct 14 16:30:10 volumio volumiobt[9631]: No default controller available Oct 14 16:30:10 volumio volumio[9135]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 16:30:10 volumio volumio[9135]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 16:30:11 volumio sudo[9638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 16:30:11 volumio sudo[9638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:11 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 14 16:30:11 volumio sudo[9638]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:11 volumio qobuz-connect[9551]: 20261014 16:30:11.698 [9551.9551] INFO SampleApp: shat down connection on UNIX socket Oct 14 16:30:11 volumio volumio[9135]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 16:30:11 volumio volumio[9135]: info: QobuzConnect: setDeactiveState invoked Oct 14 16:30:11 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:11 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:11 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 16:30:11 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 16:30:11 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 16:30:11 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 16:30:11 volumio sudo[9583]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:11 volumio volumio[9135]: info: Executing endpoint tc_getconfig Oct 14 16:30:11 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 14 16:30:11 volumio vtcs[9641]: STARTING TidalConnect services, version: 1.6.1 Oct 14 16:30:11 volumio volumiobt[9644]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:11 volumio vtcs[9641]: STARTED TidalConnect services. Oct 14 16:30:11 volumio volumio[9135]: info: Executing endpoint tc_connect Oct 14 16:30:11 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 14 16:30:11 volumio volumio[9135]: info: Connecting to TidalConnect Oct 14 16:30:11 volumio volumio[9135]: info: CoreCommandRouter::servicePushState Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::pushState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioPushState Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output update for this device Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumiobt[9650]: [83B blob data] Oct 14 16:30:12 volumio volumiobt[9650]: No default controller available Oct 14 16:30:12 volumio volumiobt[9650]: [bluetoothctl]> pairable on Oct 14 16:30:12 volumio volumiobt[9650]: No default controller available Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: 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} Oct 14 16:30:12 volumio volumio[9135]: verbose: CURRENT POSITION 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::syncState stateService stop Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::syncState currentStatus stop Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::pushState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioPushState Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output update for this device Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: No code Oct 14 16:30:12 volumio volumiobt[9650]: [bluetoothctl]> Oct 14 16:30:12 volumio volumiobt[9657]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::pushState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioPushState Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output update for this device Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::servicePushState Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::pushState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioPushState Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output update for this device Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: 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} Oct 14 16:30:12 volumio volumio[9135]: verbose: CURRENT POSITION 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::syncState stateService stop Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::syncState currentStatus stop Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::pushState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioPushState Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output update for this device Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: No code Oct 14 16:30:12 volumio volumio[9135]: info: CoreStateMachine::pushState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioPushState Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output update for this device Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Pushing multiroomSync output Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:12 volumio volumiobt[9659]: No agent is registered Oct 14 16:30:12 volumio volumiobt[9665]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:12 volumio volumiobt[9667]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:12 volumio volumio[9135]: info: Executing endpoint qc_getconfig Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.451 [9643.9643] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Getting audio outputs on start Oct 14 16:30:12 volumio volumio[9135]: info: MRS: Requesting all other devices output Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.473 [9643.9643] INFO VolumeManager: [0x525af8]: Setting new playback volume: 75 Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.474 [9643.9643] INFO VolumeManager: [0x525af8]: Setting new mute state: 0 Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.474 [9643.9643] INFO AudioStreamManager: [0x525850]: Setting new audio download buffer size: 1048576 Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.474 [9643.9643] INFO QobuzConnect: [0x5263c0]: Client initialized! Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.474 [9643.9643] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.526 [9643.9643] INFO LocalConfigManager: [0x525578]: Starting Local Configuration server Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.526 [9643.9643] INFO SampleApp: Starting Local configuration server Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.527 [9643.9643] INFO SampleApp: Connected to UNIX socket client 0x510818 Oct 14 16:30:12 volumio volumio[9135]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 16:30:12 volumio volumio[9135]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 16:30:12 volumio qobuz-connect[9643]: 20261014 16:30:12.601 [9643.9643] INFO SampleApp: Playback volume changed: 75 Oct 14 16:30:12 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:12 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:13 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 16:30:13 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:13 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 16:30:14 volumio volumio[9135]: info: TidalConnect service started! Oct 14 16:30:14 volumio volumiobt[9668]: 2026-10-14 16:30:14 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:14 volumio volumiobt[9668]: 2026-10-14 16:30:14 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:14 volumio volumiobt[9668]: 2026-10-14 16:30:14 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:14 volumio volumiobt[9668]: 2026-10-14 16:30:14 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:14 volumio volumiobt[9668]: Traceback (most recent call last): Oct 14 16:30:14 volumio volumiobt[9668]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:14 volumio volumiobt[9668]: asyncio.run(_run()) Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:14 volumio volumiobt[9668]: return runner.run(main) Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:14 volumio volumiobt[9668]: return self._loop.run_until_complete(task) Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:14 volumio volumiobt[9668]: return future.result() Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:14 volumio volumiobt[9668]: adapter_path = await find_adapter(bus) Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:14 volumio volumiobt[9668]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:14 volumio volumiobt[9668]: Exception: Bluetooth adapter not found Oct 14 16:30:14 volumio volumiobt[9668]: Traceback (most recent call last): Oct 14 16:30:14 volumio volumiobt[9668]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:14 volumio volumiobt[9668]: main() Oct 14 16:30:14 volumio volumiobt[9668]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:14 volumio volumiobt[9668]: asyncio.run(_run()) Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:14 volumio volumiobt[9668]: return runner.run(main) Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:14 volumio volumiobt[9668]: return self._loop.run_until_complete(task) Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:14 volumio volumiobt[9668]: return future.result() Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:14 volumio volumiobt[9668]: adapter_path = await find_adapter(bus) Oct 14 16:30:14 volumio volumiobt[9668]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:14 volumio volumiobt[9668]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:14 volumio volumiobt[9668]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:14 volumio volumiobt[9668]: Exception: Bluetooth adapter not found Oct 14 16:30:14 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:14 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:14 volumio systemd[1]: volumiobt.service: Consumed 2.328s CPU time. Oct 14 16:30:14 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 1. Oct 14 16:30:14 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:14 volumio systemd[1]: volumiobt.service: Consumed 2.328s CPU time. Oct 14 16:30:14 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:14 volumio volumiobt[9683]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:14 volumio sudo[9684]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:14 volumio sudo[9684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:15 volumio sudo[9684]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:15 volumio sudo[9686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:15 volumio sudo[9686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:15 volumio sudo[9686]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:15 volumio volumiobt[9688]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:15 volumio volumiobt[9691]: No default controller available Oct 14 16:30:15 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC attempt 0 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 16:30:15 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC: retrying in 10s Oct 14 16:30:15 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 16:30:15 volumio volumio[9135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 14 16:30:15 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:30:15 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:30:15 volumio mpd[9600]: 2026-10-14T16:30:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 16:30:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 14 16:30:15 volumio sudo[9465]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:15 volumio sudo[9561]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:15 volumio sudo[9513]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:15 volumio volumio[9135]: error: MPD error: The expression evaluated to a falsy value: Oct 14 16:30:15 volumio volumio[9135]: assert.ok(self.idling) Oct 14 16:30:15 volumio volumio[9135]: error: The expression evaluated to a falsy value: Oct 14 16:30:15 volumio volumio[9135]: assert.ok(self.idling) Oct 14 16:30:15 volumio volumio[9135]: error: MPD error: The expression evaluated to a falsy value: Oct 14 16:30:15 volumio volumio[9135]: assert.ok(self.idling) Oct 14 16:30:15 volumio volumio[9135]: error: The expression evaluated to a falsy value: Oct 14 16:30:15 volumio volumio[9135]: assert.ok(self.idling) Oct 14 16:30:15 volumio volumio[9135]: error: updateQueue error: null Oct 14 16:30:16 volumio volumiobt[9698]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:16 volumio volumiobt[9699]: [83B blob data] Oct 14 16:30:16 volumio volumiobt[9699]: No default controller available Oct 14 16:30:16 volumio volumiobt[9699]: [bluetoothctl]> pairable on Oct 14 16:30:16 volumio volumiobt[9699]: No default controller available Oct 14 16:30:16 volumio volumiobt[9699]: [bluetoothctl]> Oct 14 16:30:16 volumio volumiobt[9700]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:16 volumio volumiobt[9702]: No agent is registered Oct 14 16:30:16 volumio volumiobt[9703]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:16 volumio volumiobt[9704]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:17 volumio volumiobt[9705]: 2026-10-14 16:30:17 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:17 volumio volumiobt[9705]: 2026-10-14 16:30:17 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:17 volumio volumiobt[9705]: 2026-10-14 16:30:17 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:17 volumio volumiobt[9705]: 2026-10-14 16:30:17 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:17 volumio volumiobt[9705]: Traceback (most recent call last): Oct 14 16:30:17 volumio volumiobt[9705]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:17 volumio volumiobt[9705]: asyncio.run(_run()) Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:17 volumio volumiobt[9705]: return runner.run(main) Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:17 volumio volumiobt[9705]: return self._loop.run_until_complete(task) Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:17 volumio volumiobt[9705]: return future.result() Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:17 volumio volumiobt[9705]: adapter_path = await find_adapter(bus) Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:17 volumio volumiobt[9705]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:17 volumio volumiobt[9705]: Exception: Bluetooth adapter not found Oct 14 16:30:17 volumio volumiobt[9705]: Traceback (most recent call last): Oct 14 16:30:17 volumio volumiobt[9705]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:17 volumio volumiobt[9705]: main() Oct 14 16:30:17 volumio volumiobt[9705]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:17 volumio volumiobt[9705]: asyncio.run(_run()) Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:17 volumio volumiobt[9705]: return runner.run(main) Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:17 volumio volumiobt[9705]: return self._loop.run_until_complete(task) Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:17 volumio volumiobt[9705]: return future.result() Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:17 volumio volumiobt[9705]: adapter_path = await find_adapter(bus) Oct 14 16:30:17 volumio volumiobt[9705]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:17 volumio volumiobt[9705]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:17 volumio volumiobt[9705]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:17 volumio volumiobt[9705]: Exception: Bluetooth adapter not found Oct 14 16:30:17 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:17 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:17 volumio systemd[1]: volumiobt.service: Consumed 1.717s CPU time. Oct 14 16:30:18 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 2. Oct 14 16:30:18 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:18 volumio systemd[1]: volumiobt.service: Consumed 1.717s CPU time. Oct 14 16:30:18 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:18 volumio volumiobt[9721]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:18 volumio sudo[9722]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:18 volumio sudo[9722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:18 volumio sudo[9722]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:18 volumio sudo[9724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:18 volumio sudo[9724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:18 volumio sudo[9724]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:18 volumio volumiobt[9726]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:18 volumio volumiobt[9729]: No default controller available Oct 14 16:30:19 volumio volumiobt[9731]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:19 volumio volumiobt[9732]: [83B blob data] Oct 14 16:30:19 volumio volumiobt[9732]: No default controller available Oct 14 16:30:19 volumio volumiobt[9732]: [bluetoothctl]> pairable on Oct 14 16:30:19 volumio volumiobt[9732]: No default controller available Oct 14 16:30:19 volumio volumiobt[9732]: [bluetoothctl]> Oct 14 16:30:19 volumio volumiobt[9733]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:19 volumio volumiobt[9735]: No agent is registered Oct 14 16:30:19 volumio volumiobt[9736]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:19 volumio volumiobt[9737]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:20 volumio volumiobt[9738]: 2026-10-14 16:30:20 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:20 volumio volumiobt[9738]: 2026-10-14 16:30:20 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:20 volumio volumiobt[9738]: 2026-10-14 16:30:20 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::volumioGetBrowseSources Oct 14 16:30:20 volumio volumiobt[9738]: 2026-10-14 16:30:20 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:20 volumio volumiobt[9738]: Traceback (most recent call last): Oct 14 16:30:20 volumio volumiobt[9738]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:20 volumio volumiobt[9738]: asyncio.run(_run()) Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:20 volumio volumiobt[9738]: return runner.run(main) Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:20 volumio volumiobt[9738]: return self._loop.run_until_complete(task) Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:20 volumio volumiobt[9738]: return future.result() Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:20 volumio volumiobt[9738]: adapter_path = await find_adapter(bus) Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:20 volumio volumiobt[9738]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:20 volumio volumiobt[9738]: Exception: Bluetooth adapter not found Oct 14 16:30:20 volumio volumiobt[9738]: Traceback (most recent call last): Oct 14 16:30:20 volumio volumiobt[9738]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:20 volumio volumiobt[9738]: main() Oct 14 16:30:20 volumio volumiobt[9738]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:20 volumio volumiobt[9738]: asyncio.run(_run()) Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:20 volumio volumiobt[9738]: return runner.run(main) Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:20 volumio volumiobt[9738]: return self._loop.run_until_complete(task) Oct 14 16:30:20 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:20 volumio volumiobt[9738]: return future.result() Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:20 volumio volumiobt[9738]: adapter_path = await find_adapter(bus) Oct 14 16:30:20 volumio volumiobt[9738]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:20 volumio volumiobt[9738]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:20 volumio volumiobt[9738]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:20 volumio volumiobt[9738]: Exception: Bluetooth adapter not found Oct 14 16:30:20 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:20 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:20 volumio systemd[1]: volumiobt.service: Consumed 1.624s CPU time. Oct 14 16:30:21 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 3. Oct 14 16:30:21 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:21 volumio systemd[1]: volumiobt.service: Consumed 1.624s CPU time. Oct 14 16:30:21 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:21 volumio volumiobt[9740]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:21 volumio sudo[9741]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:21 volumio sudo[9741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:21 volumio sudo[9741]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:21 volumio sudo[9743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:21 volumio sudo[9743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:21 volumio sudo[9743]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:21 volumio volumiobt[9745]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:21 volumio volumiobt[9748]: No default controller available Oct 14 16:30:22 volumio volumio[9135]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 14 16:30:22 volumio volumio[9135]: info: Completed starting MyVolumio Plugin Oct 14 16:30:22 volumio volumio[9135]: [Metrics] CommandRouter: 49s 322.56ms Oct 14 16:30:22 volumio volumio[9135]: info: CoreCommandRouter::volumiosetStartupVolume Oct 14 16:30:22 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:30:22 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:30:22 volumio volumio[9135]: info: CoreCommandRouter::Close All Modals sent Oct 14 16:30:22 volumio volumio[9135]: info: CoreCommandRouter::Close All Modals sent Oct 14 16:30:22 volumio volumiobt[9752]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:22 volumio volumiobt[9753]: [83B blob data] Oct 14 16:30:22 volumio volumiobt[9753]: No default controller available Oct 14 16:30:22 volumio volumiobt[9753]: [bluetoothctl]> pairable on Oct 14 16:30:22 volumio volumiobt[9753]: No default controller available Oct 14 16:30:22 volumio volumiobt[9753]: [113B blob data] Oct 14 16:30:22 volumio volumiobt[9753]: [bluetoothctl]> Oct 14 16:30:22 volumio volumiobt[9754]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:22 volumio volumiobt[9756]: No agent is registered Oct 14 16:30:22 volumio volumiobt[9757]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:22 volumio volumiobt[9758]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:23 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 14 16:30:23 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 16:30:23 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 14 16:30:23 volumio volumiobt[9759]: 2026-10-14 16:30:23 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:23 volumio volumiobt[9759]: 2026-10-14 16:30:23 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:23 volumio volumiobt[9759]: 2026-10-14 16:30:23 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:23 volumio volumiobt[9759]: 2026-10-14 16:30:23 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:23 volumio volumiobt[9759]: Traceback (most recent call last): Oct 14 16:30:23 volumio volumiobt[9759]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:23 volumio volumiobt[9759]: asyncio.run(_run()) Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:23 volumio volumiobt[9759]: return runner.run(main) Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:23 volumio volumiobt[9759]: return self._loop.run_until_complete(task) Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:23 volumio volumiobt[9759]: return future.result() Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:23 volumio volumiobt[9759]: adapter_path = await find_adapter(bus) Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:23 volumio volumiobt[9759]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:23 volumio volumiobt[9759]: Exception: Bluetooth adapter not found Oct 14 16:30:23 volumio volumiobt[9759]: Traceback (most recent call last): Oct 14 16:30:23 volumio volumiobt[9759]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:23 volumio volumiobt[9759]: main() Oct 14 16:30:23 volumio volumiobt[9759]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:23 volumio volumiobt[9759]: asyncio.run(_run()) Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:23 volumio volumiobt[9759]: return runner.run(main) Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:23 volumio volumiobt[9759]: return self._loop.run_until_complete(task) Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:23 volumio volumiobt[9759]: return future.result() Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:23 volumio volumiobt[9759]: adapter_path = await find_adapter(bus) Oct 14 16:30:23 volumio volumiobt[9759]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:23 volumio volumiobt[9759]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:23 volumio volumiobt[9759]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:23 volumio volumiobt[9759]: Exception: Bluetooth adapter not found Oct 14 16:30:23 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:23 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:23 volumio systemd[1]: volumiobt.service: Consumed 1.620s CPU time. Oct 14 16:30:24 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 4. Oct 14 16:30:24 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:24 volumio systemd[1]: volumiobt.service: Consumed 1.620s CPU time. Oct 14 16:30:24 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:24 volumio volumiobt[9764]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:24 volumio sudo[9765]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:24 volumio sudo[9765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:24 volumio sudo[9765]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:24 volumio sudo[9767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:24 volumio sudo[9767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:24 volumio sudo[9767]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:24 volumio volumiobt[9769]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:24 volumio volumiobt[9772]: No default controller available Oct 14 16:30:25 volumio volumiobt[9777]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:25 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC attempt 1 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 16:30:25 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC: retrying in 10s Oct 14 16:30:25 volumio volumiobt[9778]: [83B blob data] Oct 14 16:30:25 volumio volumiobt[9778]: No default controller available Oct 14 16:30:25 volumio volumiobt[9778]: [bluetoothctl]> pairable on Oct 14 16:30:25 volumio volumiobt[9778]: No default controller available Oct 14 16:30:25 volumio volumiobt[9778]: [113B blob data] Oct 14 16:30:25 volumio volumiobt[9778]: [bluetoothctl]> Oct 14 16:30:25 volumio volumiobt[9779]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:25 volumio volumiobt[9781]: No agent is registered Oct 14 16:30:25 volumio volumiobt[9782]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:25 volumio volumiobt[9783]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:26 volumio volumiobt[9784]: 2026-10-14 16:30:26 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:26 volumio volumiobt[9784]: 2026-10-14 16:30:26 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:26 volumio volumiobt[9784]: 2026-10-14 16:30:26 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:26 volumio volumiobt[9784]: 2026-10-14 16:30:26 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:26 volumio volumiobt[9784]: Traceback (most recent call last): Oct 14 16:30:26 volumio volumiobt[9784]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:26 volumio volumiobt[9784]: asyncio.run(_run()) Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:26 volumio volumiobt[9784]: return runner.run(main) Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:26 volumio volumiobt[9784]: return self._loop.run_until_complete(task) Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:26 volumio volumiobt[9784]: return future.result() Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:26 volumio volumiobt[9784]: adapter_path = await find_adapter(bus) Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:26 volumio volumiobt[9784]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:26 volumio volumiobt[9784]: Exception: Bluetooth adapter not found Oct 14 16:30:26 volumio volumiobt[9784]: Traceback (most recent call last): Oct 14 16:30:26 volumio volumiobt[9784]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:26 volumio volumiobt[9784]: main() Oct 14 16:30:26 volumio volumiobt[9784]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:26 volumio volumiobt[9784]: asyncio.run(_run()) Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:26 volumio volumiobt[9784]: return runner.run(main) Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:26 volumio volumiobt[9784]: return self._loop.run_until_complete(task) Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:26 volumio volumiobt[9784]: return future.result() Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:26 volumio volumiobt[9784]: adapter_path = await find_adapter(bus) Oct 14 16:30:26 volumio volumiobt[9784]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:26 volumio volumiobt[9784]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:26 volumio volumiobt[9784]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:26 volumio volumiobt[9784]: Exception: Bluetooth adapter not found Oct 14 16:30:26 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:26 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:26 volumio systemd[1]: volumiobt.service: Consumed 1.214s CPU time. Oct 14 16:30:26 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 5. Oct 14 16:30:26 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:26 volumio systemd[1]: volumiobt.service: Consumed 1.214s CPU time. Oct 14 16:30:26 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:26 volumio volumiobt[9786]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:26 volumio sudo[9787]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:26 volumio sudo[9787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:26 volumio sudo[9787]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:26 volumio sudo[9803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:26 volumio sudo[9803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:27 volumio sudo[9803]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:27 volumio volumiobt[9805]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:27 volumio volumiobt[9808]: No default controller available Oct 14 16:30:28 volumio volumiobt[9816]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:28 volumio volumiobt[9817]: [83B blob data] Oct 14 16:30:28 volumio volumiobt[9817]: No default controller available Oct 14 16:30:28 volumio volumiobt[9817]: [bluetoothctl]> pairable on Oct 14 16:30:28 volumio volumiobt[9817]: No default controller available Oct 14 16:30:28 volumio volumiobt[9817]: [113B blob data] Oct 14 16:30:28 volumio volumiobt[9817]: [bluetoothctl]> Oct 14 16:30:28 volumio volumiobt[9818]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:28 volumio volumiobt[9820]: No agent is registered Oct 14 16:30:28 volumio volumiobt[9821]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:28 volumio volumiobt[9822]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:29 volumio volumio[9135]: info: BOOT COMPLETED Oct 14 16:30:29 volumio volumiobt[9823]: 2026-10-14 16:30:29 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:29 volumio volumiobt[9823]: 2026-10-14 16:30:29 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:29 volumio volumiobt[9823]: 2026-10-14 16:30:29 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:29 volumio volumiobt[9823]: 2026-10-14 16:30:29 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:29 volumio volumiobt[9823]: Traceback (most recent call last): Oct 14 16:30:29 volumio volumiobt[9823]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:29 volumio volumiobt[9823]: asyncio.run(_run()) Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:29 volumio volumiobt[9823]: return runner.run(main) Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:29 volumio volumiobt[9823]: return self._loop.run_until_complete(task) Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:29 volumio volumiobt[9823]: return future.result() Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:29 volumio volumiobt[9823]: adapter_path = await find_adapter(bus) Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:29 volumio volumiobt[9823]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:29 volumio volumiobt[9823]: Exception: Bluetooth adapter not found Oct 14 16:30:29 volumio volumiobt[9823]: Traceback (most recent call last): Oct 14 16:30:29 volumio volumiobt[9823]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:29 volumio volumiobt[9823]: main() Oct 14 16:30:29 volumio volumiobt[9823]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:29 volumio volumiobt[9823]: asyncio.run(_run()) Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:29 volumio volumiobt[9823]: return runner.run(main) Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:29 volumio volumiobt[9823]: return self._loop.run_until_complete(task) Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:29 volumio volumiobt[9823]: return future.result() Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:29 volumio volumiobt[9823]: adapter_path = await find_adapter(bus) Oct 14 16:30:29 volumio volumiobt[9823]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:29 volumio volumiobt[9823]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:29 volumio volumiobt[9823]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:29 volumio volumiobt[9823]: Exception: Bluetooth adapter not found Oct 14 16:30:29 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:29 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:29 volumio systemd[1]: volumiobt.service: Consumed 1.686s CPU time. Oct 14 16:30:30 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 6. Oct 14 16:30:30 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:30 volumio systemd[1]: volumiobt.service: Consumed 1.686s CPU time. Oct 14 16:30:30 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:30 volumio volumiobt[9825]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:30 volumio sudo[9826]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:30 volumio sudo[9826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:30 volumio sudo[9826]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:30 volumio sudo[9828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:30 volumio sudo[9828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:30 volumio sudo[9828]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:30 volumio volumiobt[9830]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:30 volumio volumiobt[9833]: No default controller available Oct 14 16:30:31 volumio volumiobt[9835]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:31 volumio volumiobt[9836]: [83B blob data] Oct 14 16:30:31 volumio volumiobt[9836]: No default controller available Oct 14 16:30:31 volumio volumiobt[9836]: [bluetoothctl]> pairable on Oct 14 16:30:31 volumio volumiobt[9836]: No default controller available Oct 14 16:30:31 volumio volumiobt[9836]: [113B blob data] Oct 14 16:30:31 volumio volumiobt[9836]: [bluetoothctl]> Oct 14 16:30:31 volumio volumiobt[9837]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:31 volumio volumiobt[9839]: No agent is registered Oct 14 16:30:31 volumio volumiobt[9840]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:31 volumio volumiobt[9841]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:32 volumio volumiobt[9842]: 2026-10-14 16:30:32 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:32 volumio volumiobt[9842]: 2026-10-14 16:30:32 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:32 volumio volumiobt[9842]: 2026-10-14 16:30:32 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:32 volumio volumiobt[9842]: 2026-10-14 16:30:32 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:32 volumio volumiobt[9842]: Traceback (most recent call last): Oct 14 16:30:32 volumio volumiobt[9842]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:32 volumio volumiobt[9842]: asyncio.run(_run()) Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:32 volumio volumiobt[9842]: return runner.run(main) Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:32 volumio volumiobt[9842]: return self._loop.run_until_complete(task) Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:32 volumio volumiobt[9842]: return future.result() Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:32 volumio volumiobt[9842]: adapter_path = await find_adapter(bus) Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:32 volumio volumiobt[9842]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:32 volumio volumiobt[9842]: Exception: Bluetooth adapter not found Oct 14 16:30:32 volumio volumiobt[9842]: Traceback (most recent call last): Oct 14 16:30:32 volumio volumiobt[9842]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:32 volumio volumiobt[9842]: main() Oct 14 16:30:32 volumio volumiobt[9842]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:32 volumio volumiobt[9842]: asyncio.run(_run()) Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:32 volumio volumiobt[9842]: return runner.run(main) Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:32 volumio volumiobt[9842]: return self._loop.run_until_complete(task) Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:32 volumio volumiobt[9842]: return future.result() Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:32 volumio volumiobt[9842]: adapter_path = await find_adapter(bus) Oct 14 16:30:32 volumio volumiobt[9842]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:32 volumio volumiobt[9842]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:32 volumio volumiobt[9842]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:32 volumio volumiobt[9842]: Exception: Bluetooth adapter not found Oct 14 16:30:32 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:32 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:32 volumio systemd[1]: volumiobt.service: Consumed 1.626s CPU time. Oct 14 16:30:33 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 7. Oct 14 16:30:33 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:33 volumio systemd[1]: volumiobt.service: Consumed 1.626s CPU time. Oct 14 16:30:33 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:33 volumio volumiobt[9844]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:33 volumio sudo[9845]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:33 volumio sudo[9845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:33 volumio sudo[9845]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:33 volumio sudo[9847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:33 volumio sudo[9847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:33 volumio sudo[9847]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:33 volumio volumiobt[9849]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:33 volumio volumiobt[9852]: No default controller available Oct 14 16:30:33 volumio volumio-remote-updater[720]: Test mode disabled Oct 14 16:30:33 volumio volumio-remote-updater[720]: Alpha mode disabled Oct 14 16:30:33 volumio volumio-remote-updater[720]: Alpha legacy test mode disabled Oct 14 16:30:33 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 14 16:30:34 volumio volumio[9135]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 14 16:30:34 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 14 16:30:34 volumio volumiobt[9855]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:34 volumio volumiobt[9856]: [165B blob data] Oct 14 16:30:34 volumio volumiobt[9856]: [bluetoothctl]> discoverable on Oct 14 16:30:34 volumio volumiobt[9856]: No default controller available Oct 14 16:30:34 volumio volumiobt[9856]: [bluetoothctl]> pairable on Oct 14 16:30:34 volumio volumiobt[9856]: No default controller available Oct 14 16:30:34 volumio volumiobt[9856]: [bluetoothctl]> Oct 14 16:30:34 volumio volumiobt[9857]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:34 volumio volumiobt[9859]: No agent is registered Oct 14 16:30:34 volumio volumiobt[9861]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:34 volumio volumiobt[9862]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:35 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC attempt 2 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 16:30:35 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC: retrying in 10s Oct 14 16:30:35 volumio volumiobt[9863]: 2026-10-14 16:30:35 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:35 volumio volumiobt[9863]: 2026-10-14 16:30:35 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:35 volumio volumiobt[9863]: 2026-10-14 16:30:35 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:35 volumio volumiobt[9863]: 2026-10-14 16:30:35 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:35 volumio volumiobt[9863]: Traceback (most recent call last): Oct 14 16:30:35 volumio volumiobt[9863]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:35 volumio volumiobt[9863]: asyncio.run(_run()) Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:35 volumio volumiobt[9863]: return runner.run(main) Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:35 volumio volumiobt[9863]: return self._loop.run_until_complete(task) Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:35 volumio volumiobt[9863]: return future.result() Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:35 volumio volumiobt[9863]: adapter_path = await find_adapter(bus) Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:35 volumio volumiobt[9863]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:35 volumio volumiobt[9863]: Exception: Bluetooth adapter not found Oct 14 16:30:35 volumio volumiobt[9863]: Traceback (most recent call last): Oct 14 16:30:35 volumio volumiobt[9863]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:35 volumio volumiobt[9863]: main() Oct 14 16:30:35 volumio volumiobt[9863]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:35 volumio volumiobt[9863]: asyncio.run(_run()) Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:35 volumio volumiobt[9863]: return runner.run(main) Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:35 volumio volumiobt[9863]: return self._loop.run_until_complete(task) Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:35 volumio volumiobt[9863]: return future.result() Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:35 volumio volumiobt[9863]: adapter_path = await find_adapter(bus) Oct 14 16:30:35 volumio volumiobt[9863]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:35 volumio volumiobt[9863]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:35 volumio volumiobt[9863]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:35 volumio volumiobt[9863]: Exception: Bluetooth adapter not found Oct 14 16:30:35 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:35 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:35 volumio systemd[1]: volumiobt.service: Consumed 1.682s CPU time. Oct 14 16:30:36 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 8. Oct 14 16:30:36 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:36 volumio systemd[1]: volumiobt.service: Consumed 1.682s CPU time. Oct 14 16:30:36 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:36 volumio volumiobt[9868]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:36 volumio sudo[9869]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:36 volumio sudo[9869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:36 volumio sudo[9869]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:36 volumio sudo[9871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:36 volumio sudo[9871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:36 volumio sudo[9871]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:36 volumio volumiobt[9873]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:36 volumio volumiobt[9876]: No default controller available Oct 14 16:30:37 volumio volumiobt[9892]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:37 volumio volumiobt[9893]: [165B blob data] Oct 14 16:30:37 volumio volumiobt[9893]: [bluetoothctl]> discoverable on Oct 14 16:30:37 volumio volumiobt[9893]: No default controller available Oct 14 16:30:37 volumio volumiobt[9893]: [bluetoothctl]> pairable on Oct 14 16:30:37 volumio volumiobt[9893]: No default controller available Oct 14 16:30:37 volumio volumiobt[9893]: [bluetoothctl]> Oct 14 16:30:37 volumio volumiobt[9894]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:37 volumio volumiobt[9896]: No agent is registered Oct 14 16:30:37 volumio volumiobt[9897]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:37 volumio volumiobt[9898]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:38 volumio volumiobt[9899]: 2026-10-14 16:30:38 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:38 volumio volumiobt[9899]: 2026-10-14 16:30:38 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:38 volumio volumiobt[9899]: 2026-10-14 16:30:38 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:38 volumio volumiobt[9899]: 2026-10-14 16:30:38 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:38 volumio volumiobt[9899]: Traceback (most recent call last): Oct 14 16:30:38 volumio volumiobt[9899]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:38 volumio volumiobt[9899]: asyncio.run(_run()) Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:38 volumio volumiobt[9899]: return runner.run(main) Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:38 volumio volumiobt[9899]: return self._loop.run_until_complete(task) Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:38 volumio volumiobt[9899]: return future.result() Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:38 volumio volumiobt[9899]: adapter_path = await find_adapter(bus) Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:38 volumio volumiobt[9899]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:38 volumio volumiobt[9899]: Exception: Bluetooth adapter not found Oct 14 16:30:38 volumio volumiobt[9899]: Traceback (most recent call last): Oct 14 16:30:38 volumio volumiobt[9899]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:38 volumio volumiobt[9899]: main() Oct 14 16:30:38 volumio volumiobt[9899]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:38 volumio volumiobt[9899]: asyncio.run(_run()) Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:38 volumio volumiobt[9899]: return runner.run(main) Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:38 volumio volumiobt[9899]: return self._loop.run_until_complete(task) Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:38 volumio volumiobt[9899]: return future.result() Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:38 volumio volumiobt[9899]: adapter_path = await find_adapter(bus) Oct 14 16:30:38 volumio volumiobt[9899]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:38 volumio volumiobt[9899]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:38 volumio volumiobt[9899]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:38 volumio volumiobt[9899]: Exception: Bluetooth adapter not found Oct 14 16:30:38 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:38 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:38 volumio systemd[1]: volumiobt.service: Consumed 1.667s CPU time. Oct 14 16:30:39 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 9. Oct 14 16:30:39 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:39 volumio systemd[1]: volumiobt.service: Consumed 1.667s CPU time. Oct 14 16:30:39 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:39 volumio volumiobt[9901]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:39 volumio sudo[9902]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:39 volumio sudo[9902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:39 volumio sudo[9902]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:39 volumio sudo[9904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:39 volumio sudo[9904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:39 volumio sudo[9904]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:39 volumio volumiobt[9906]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:39 volumio volumiobt[9909]: No default controller available Oct 14 16:30:40 volumio volumiobt[9911]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:40 volumio volumiobt[9912]: [83B blob data] Oct 14 16:30:40 volumio volumiobt[9912]: No default controller available Oct 14 16:30:40 volumio volumiobt[9912]: [bluetoothctl]> pairable on Oct 14 16:30:40 volumio volumiobt[9912]: No default controller available Oct 14 16:30:40 volumio volumiobt[9912]: [113B blob data] Oct 14 16:30:40 volumio volumiobt[9912]: [bluetoothctl]> Oct 14 16:30:40 volumio volumiobt[9913]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:40 volumio volumiobt[9915]: No agent is registered Oct 14 16:30:40 volumio volumiobt[9916]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:40 volumio volumiobt[9917]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:41 volumio volumiobt[9918]: 2026-10-14 16:30:41 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:41 volumio volumiobt[9918]: 2026-10-14 16:30:41 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:41 volumio volumiobt[9918]: 2026-10-14 16:30:41 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:41 volumio volumiobt[9918]: 2026-10-14 16:30:41 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:41 volumio volumiobt[9918]: Traceback (most recent call last): Oct 14 16:30:41 volumio volumiobt[9918]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:41 volumio volumiobt[9918]: asyncio.run(_run()) Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:41 volumio volumiobt[9918]: return runner.run(main) Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:41 volumio volumiobt[9918]: return self._loop.run_until_complete(task) Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:41 volumio volumiobt[9918]: return future.result() Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:41 volumio volumiobt[9918]: adapter_path = await find_adapter(bus) Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:41 volumio volumiobt[9918]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:41 volumio volumiobt[9918]: Exception: Bluetooth adapter not found Oct 14 16:30:41 volumio volumiobt[9918]: Traceback (most recent call last): Oct 14 16:30:41 volumio volumiobt[9918]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:41 volumio volumiobt[9918]: main() Oct 14 16:30:41 volumio volumiobt[9918]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:41 volumio volumiobt[9918]: asyncio.run(_run()) Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:41 volumio volumiobt[9918]: return runner.run(main) Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:41 volumio volumiobt[9918]: return self._loop.run_until_complete(task) Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:41 volumio volumiobt[9918]: return future.result() Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:41 volumio volumiobt[9918]: adapter_path = await find_adapter(bus) Oct 14 16:30:41 volumio volumiobt[9918]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:41 volumio volumiobt[9918]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:41 volumio volumiobt[9918]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:41 volumio volumiobt[9918]: Exception: Bluetooth adapter not found Oct 14 16:30:41 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:41 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:41 volumio systemd[1]: volumiobt.service: Consumed 1.637s CPU time. Oct 14 16:30:42 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 10. Oct 14 16:30:42 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:42 volumio systemd[1]: volumiobt.service: Consumed 1.637s CPU time. Oct 14 16:30:42 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:42 volumio volumiobt[9920]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:42 volumio sudo[9921]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:42 volumio sudo[9921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:42 volumio sudo[9921]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:42 volumio sudo[9923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:42 volumio sudo[9923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:42 volumio sudo[9923]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:42 volumio volumiobt[9925]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:42 volumio volumiobt[9928]: No default controller available Oct 14 16:30:43 volumio volumiobt[9936]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:43 volumio volumiobt[9937]: [83B blob data] Oct 14 16:30:43 volumio volumiobt[9937]: No default controller available Oct 14 16:30:43 volumio volumiobt[9937]: [bluetoothctl]> pairable on Oct 14 16:30:43 volumio volumiobt[9937]: No default controller available Oct 14 16:30:43 volumio volumiobt[9937]: [bluetoothctl]> Oct 14 16:30:43 volumio volumiobt[9938]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:43 volumio volumiobt[9940]: No agent is registered Oct 14 16:30:43 volumio volumiobt[9941]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:43 volumio volumiobt[9942]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:44 volumio volumiobt[9943]: 2026-10-14 16:30:44 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:44 volumio volumiobt[9943]: 2026-10-14 16:30:44 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:44 volumio volumiobt[9943]: 2026-10-14 16:30:44 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:44 volumio volumiobt[9943]: 2026-10-14 16:30:44 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:44 volumio volumiobt[9943]: Traceback (most recent call last): Oct 14 16:30:44 volumio volumiobt[9943]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:44 volumio volumiobt[9943]: asyncio.run(_run()) Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:44 volumio volumiobt[9943]: return runner.run(main) Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:44 volumio volumiobt[9943]: return self._loop.run_until_complete(task) Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:44 volumio volumiobt[9943]: return future.result() Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:44 volumio volumiobt[9943]: adapter_path = await find_adapter(bus) Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:44 volumio volumiobt[9943]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:44 volumio volumiobt[9943]: Exception: Bluetooth adapter not found Oct 14 16:30:44 volumio volumiobt[9943]: Traceback (most recent call last): Oct 14 16:30:44 volumio volumiobt[9943]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:44 volumio volumiobt[9943]: main() Oct 14 16:30:44 volumio volumiobt[9943]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:44 volumio volumiobt[9943]: asyncio.run(_run()) Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:44 volumio volumiobt[9943]: return runner.run(main) Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:44 volumio volumiobt[9943]: return self._loop.run_until_complete(task) Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:44 volumio volumiobt[9943]: return future.result() Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:44 volumio volumiobt[9943]: adapter_path = await find_adapter(bus) Oct 14 16:30:44 volumio volumiobt[9943]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:44 volumio volumiobt[9943]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:44 volumio volumiobt[9943]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:44 volumio volumiobt[9943]: Exception: Bluetooth adapter not found Oct 14 16:30:44 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:44 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:44 volumio systemd[1]: volumiobt.service: Consumed 1.130s CPU time. Oct 14 16:30:44 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 11. Oct 14 16:30:44 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:44 volumio systemd[1]: volumiobt.service: Consumed 1.130s CPU time. Oct 14 16:30:44 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:44 volumio volumiobt[9945]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:44 volumio sudo[9946]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:44 volumio sudo[9946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:44 volumio sudo[9946]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:44 volumio sudo[9948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:44 volumio sudo[9948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:44 volumio sudo[9948]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:44 volumio volumiobt[9950]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:44 volumio volumiobt[9953]: No default controller available Oct 14 16:30:45 volumio volumio[9135]: BT PLUGIN MESSAGE: SYNC attempt 3 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 16:30:45 volumio volumiobt[9958]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:45 volumio volumiobt[9959]: [83B blob data] Oct 14 16:30:45 volumio volumiobt[9959]: No default controller available Oct 14 16:30:45 volumio volumiobt[9959]: [bluetoothctl]> pairable on Oct 14 16:30:45 volumio volumiobt[9959]: No default controller available Oct 14 16:30:45 volumio volumiobt[9959]: [113B blob data] Oct 14 16:30:45 volumio volumiobt[9959]: [bluetoothctl]> Oct 14 16:30:45 volumio volumiobt[9960]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:46 volumio volumiobt[9962]: No agent is registered Oct 14 16:30:46 volumio volumiobt[9963]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:46 volumio volumiobt[9964]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:47 volumio volumiobt[9965]: 2026-10-14 16:30:47 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:47 volumio volumiobt[9965]: 2026-10-14 16:30:47 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:47 volumio volumiobt[9965]: 2026-10-14 16:30:47 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:47 volumio volumiobt[9965]: 2026-10-14 16:30:47 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:47 volumio volumiobt[9965]: Traceback (most recent call last): Oct 14 16:30:47 volumio volumiobt[9965]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:47 volumio volumiobt[9965]: asyncio.run(_run()) Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:47 volumio volumiobt[9965]: return runner.run(main) Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:47 volumio volumiobt[9965]: return self._loop.run_until_complete(task) Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:47 volumio volumiobt[9965]: return future.result() Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:47 volumio volumiobt[9965]: adapter_path = await find_adapter(bus) Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:47 volumio volumiobt[9965]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:47 volumio volumiobt[9965]: Exception: Bluetooth adapter not found Oct 14 16:30:47 volumio volumiobt[9965]: Traceback (most recent call last): Oct 14 16:30:47 volumio volumiobt[9965]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:47 volumio volumiobt[9965]: main() Oct 14 16:30:47 volumio volumiobt[9965]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:47 volumio volumiobt[9965]: asyncio.run(_run()) Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:47 volumio volumiobt[9965]: return runner.run(main) Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:47 volumio volumiobt[9965]: return self._loop.run_until_complete(task) Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:47 volumio volumiobt[9965]: return future.result() Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:47 volumio volumiobt[9965]: adapter_path = await find_adapter(bus) Oct 14 16:30:47 volumio volumiobt[9965]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:47 volumio volumiobt[9965]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:47 volumio volumiobt[9965]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:47 volumio volumiobt[9965]: Exception: Bluetooth adapter not found Oct 14 16:30:47 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:47 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:47 volumio systemd[1]: volumiobt.service: Consumed 1.675s CPU time. Oct 14 16:30:47 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 12. Oct 14 16:30:47 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:47 volumio systemd[1]: volumiobt.service: Consumed 1.675s CPU time. Oct 14 16:30:47 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:47 volumio volumiobt[9981]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:47 volumio sudo[9982]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:47 volumio sudo[9982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:47 volumio sudo[9982]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:47 volumio sudo[9984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:47 volumio sudo[9984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:47 volumio sudo[9984]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:47 volumio volumiobt[9986]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:48 volumio volumiobt[9989]: No default controller available Oct 14 16:30:49 volumio volumiobt[9991]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:49 volumio volumiobt[9992]: [83B blob data] Oct 14 16:30:49 volumio volumiobt[9992]: No default controller available Oct 14 16:30:49 volumio volumiobt[9992]: [bluetoothctl]> pairable on Oct 14 16:30:49 volumio volumiobt[9992]: No default controller available Oct 14 16:30:49 volumio volumiobt[9992]: [bluetoothctl]> Oct 14 16:30:49 volumio volumiobt[9993]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:49 volumio volumiobt[9995]: No agent is registered Oct 14 16:30:49 volumio volumiobt[9996]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:49 volumio volumiobt[9997]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:50 volumio volumiobt[9998]: 2026-10-14 16:30:50 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:50 volumio volumiobt[9998]: 2026-10-14 16:30:50 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:50 volumio volumiobt[9998]: 2026-10-14 16:30:50 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:50 volumio volumiobt[9998]: 2026-10-14 16:30:50 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:50 volumio volumiobt[9998]: Traceback (most recent call last): Oct 14 16:30:50 volumio volumiobt[9998]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:50 volumio volumiobt[9998]: asyncio.run(_run()) Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:50 volumio volumiobt[9998]: return runner.run(main) Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:50 volumio volumiobt[9998]: return self._loop.run_until_complete(task) Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:50 volumio volumiobt[9998]: return future.result() Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:50 volumio volumiobt[9998]: adapter_path = await find_adapter(bus) Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:50 volumio volumiobt[9998]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:50 volumio volumiobt[9998]: Exception: Bluetooth adapter not found Oct 14 16:30:50 volumio volumiobt[9998]: Traceback (most recent call last): Oct 14 16:30:50 volumio volumiobt[9998]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:50 volumio volumiobt[9998]: main() Oct 14 16:30:50 volumio volumiobt[9998]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:50 volumio volumiobt[9998]: asyncio.run(_run()) Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:50 volumio volumiobt[9998]: return runner.run(main) Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:50 volumio volumiobt[9998]: return self._loop.run_until_complete(task) Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:50 volumio volumiobt[9998]: return future.result() Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:50 volumio volumiobt[9998]: adapter_path = await find_adapter(bus) Oct 14 16:30:50 volumio volumiobt[9998]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:50 volumio volumiobt[9998]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:50 volumio volumiobt[9998]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:50 volumio volumiobt[9998]: Exception: Bluetooth adapter not found Oct 14 16:30:50 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:50 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:50 volumio systemd[1]: volumiobt.service: Consumed 1.582s CPU time. Oct 14 16:30:50 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 13. Oct 14 16:30:50 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:50 volumio systemd[1]: volumiobt.service: Consumed 1.582s CPU time. Oct 14 16:30:50 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:50 volumio volumiobt[10000]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:50 volumio sudo[10001]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:50 volumio sudo[10001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:50 volumio sudo[10001]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:50 volumio sudo[10003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:50 volumio sudo[10003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:50 volumio sudo[10003]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:51 volumio volumiobt[10005]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:51 volumio volumiobt[10008]: No default controller available Oct 14 16:30:52 volumio volumiobt[10010]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:52 volumio volumiobt[10011]: [83B blob data] Oct 14 16:30:52 volumio volumiobt[10011]: No default controller available Oct 14 16:30:52 volumio volumiobt[10011]: [bluetoothctl]> pairable on Oct 14 16:30:52 volumio volumiobt[10011]: No default controller available Oct 14 16:30:52 volumio volumiobt[10011]: [bluetoothctl]> Oct 14 16:30:52 volumio volumiobt[10012]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:52 volumio volumiobt[10014]: No agent is registered Oct 14 16:30:52 volumio volumiobt[10015]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:52 volumio volumiobt[10016]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:53 volumio volumiobt[10017]: 2026-10-14 16:30:53 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:53 volumio volumiobt[10017]: 2026-10-14 16:30:53 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:53 volumio volumiobt[10017]: 2026-10-14 16:30:53 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:53 volumio volumiobt[10017]: 2026-10-14 16:30:53 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:53 volumio volumiobt[10017]: Traceback (most recent call last): Oct 14 16:30:53 volumio volumiobt[10017]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:53 volumio volumiobt[10017]: asyncio.run(_run()) Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:53 volumio volumiobt[10017]: return runner.run(main) Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:53 volumio volumiobt[10017]: return self._loop.run_until_complete(task) Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:53 volumio volumiobt[10017]: return future.result() Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:53 volumio volumiobt[10017]: adapter_path = await find_adapter(bus) Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:53 volumio volumiobt[10017]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:53 volumio volumiobt[10017]: Exception: Bluetooth adapter not found Oct 14 16:30:53 volumio volumiobt[10017]: Traceback (most recent call last): Oct 14 16:30:53 volumio volumiobt[10017]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:53 volumio volumiobt[10017]: main() Oct 14 16:30:53 volumio volumiobt[10017]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:53 volumio volumiobt[10017]: asyncio.run(_run()) Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:53 volumio volumiobt[10017]: return runner.run(main) Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:53 volumio volumiobt[10017]: return self._loop.run_until_complete(task) Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:53 volumio volumiobt[10017]: return future.result() Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:53 volumio volumiobt[10017]: adapter_path = await find_adapter(bus) Oct 14 16:30:53 volumio volumiobt[10017]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:53 volumio volumiobt[10017]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:53 volumio volumiobt[10017]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:53 volumio volumiobt[10017]: Exception: Bluetooth adapter not found Oct 14 16:30:53 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:53 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:53 volumio systemd[1]: volumiobt.service: Consumed 1.600s CPU time. Oct 14 16:30:53 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 14. Oct 14 16:30:53 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:53 volumio systemd[1]: volumiobt.service: Consumed 1.600s CPU time. Oct 14 16:30:53 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:53 volumio volumiobt[10019]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:53 volumio sudo[10020]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:53 volumio sudo[10020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:53 volumio sudo[10020]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:53 volumio sudo[10022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:53 volumio sudo[10022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:53 volumio sudo[10022]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:53 volumio volumiobt[10024]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:54 volumio volumiobt[10027]: No default controller available Oct 14 16:30:55 volumio volumiobt[10030]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:55 volumio volumiobt[10031]: [165B blob data] Oct 14 16:30:55 volumio volumiobt[10031]: [bluetoothctl]> discoverable on Oct 14 16:30:55 volumio volumiobt[10031]: No default controller available Oct 14 16:30:55 volumio volumiobt[10031]: [bluetoothctl]> pairable on Oct 14 16:30:55 volumio volumiobt[10031]: No default controller available Oct 14 16:30:55 volumio volumiobt[10031]: [bluetoothctl]> Oct 14 16:30:55 volumio volumiobt[10032]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:55 volumio volumiobt[10034]: No agent is registered Oct 14 16:30:55 volumio volumiobt[10035]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:55 volumio volumiobt[10036]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:56 volumio volumiobt[10037]: 2026-10-14 16:30:56 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:56 volumio volumiobt[10037]: 2026-10-14 16:30:56 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:56 volumio volumiobt[10037]: 2026-10-14 16:30:56 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:56 volumio volumiobt[10037]: 2026-10-14 16:30:56 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:56 volumio volumiobt[10037]: Traceback (most recent call last): Oct 14 16:30:56 volumio volumiobt[10037]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:56 volumio volumiobt[10037]: asyncio.run(_run()) Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:56 volumio volumiobt[10037]: return runner.run(main) Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:56 volumio volumiobt[10037]: return self._loop.run_until_complete(task) Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:56 volumio volumiobt[10037]: return future.result() Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:56 volumio volumiobt[10037]: adapter_path = await find_adapter(bus) Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:56 volumio volumiobt[10037]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:56 volumio volumiobt[10037]: Exception: Bluetooth adapter not found Oct 14 16:30:56 volumio volumiobt[10037]: Traceback (most recent call last): Oct 14 16:30:56 volumio volumiobt[10037]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:56 volumio volumiobt[10037]: main() Oct 14 16:30:56 volumio volumiobt[10037]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:56 volumio volumiobt[10037]: asyncio.run(_run()) Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:56 volumio volumiobt[10037]: return runner.run(main) Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:56 volumio volumiobt[10037]: return self._loop.run_until_complete(task) Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:56 volumio volumiobt[10037]: return future.result() Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:56 volumio volumiobt[10037]: adapter_path = await find_adapter(bus) Oct 14 16:30:56 volumio volumiobt[10037]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:56 volumio volumiobt[10037]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:56 volumio volumiobt[10037]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:56 volumio volumiobt[10037]: Exception: Bluetooth adapter not found Oct 14 16:30:56 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:56 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:56 volumio systemd[1]: volumiobt.service: Consumed 1.211s CPU time. Oct 14 16:30:56 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 15. Oct 14 16:30:56 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:56 volumio systemd[1]: volumiobt.service: Consumed 1.211s CPU time. Oct 14 16:30:56 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:56 volumio volumiobt[10039]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:56 volumio sudo[10040]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:56 volumio sudo[10040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:56 volumio sudo[10040]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:56 volumio sudo[10042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:56 volumio sudo[10042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:56 volumio sudo[10042]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:56 volumio volumiobt[10044]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:56 volumio volumiobt[10047]: No default controller available Oct 14 16:30:57 volumio volumiobt[10063]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:30:57 volumio volumiobt[10064]: [83B blob data] Oct 14 16:30:57 volumio volumiobt[10064]: No default controller available Oct 14 16:30:57 volumio volumiobt[10064]: [bluetoothctl]> pairable on Oct 14 16:30:57 volumio volumiobt[10064]: No default controller available Oct 14 16:30:57 volumio volumiobt[10064]: [113B blob data] Oct 14 16:30:57 volumio volumiobt[10064]: [bluetoothctl]> Oct 14 16:30:57 volumio volumiobt[10067]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:30:58 volumio volumiobt[10071]: No agent is registered Oct 14 16:30:58 volumio volumiobt[10075]: INFO [BTSTART] Agent registered successfully. Oct 14 16:30:58 volumio volumiobt[10076]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:30:59 volumio volumiobt[10077]: 2026-10-14 16:30:59 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:30:59 volumio volumiobt[10077]: 2026-10-14 16:30:59 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:30:59 volumio volumiobt[10077]: 2026-10-14 16:30:59 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:30:59 volumio volumiobt[10077]: 2026-10-14 16:30:59 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:30:59 volumio volumiobt[10077]: Traceback (most recent call last): Oct 14 16:30:59 volumio volumiobt[10077]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:59 volumio volumiobt[10077]: asyncio.run(_run()) Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:59 volumio volumiobt[10077]: return runner.run(main) Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:59 volumio volumiobt[10077]: return self._loop.run_until_complete(task) Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:59 volumio volumiobt[10077]: return future.result() Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:59 volumio volumiobt[10077]: adapter_path = await find_adapter(bus) Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:59 volumio volumiobt[10077]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:59 volumio volumiobt[10077]: Exception: Bluetooth adapter not found Oct 14 16:30:59 volumio volumiobt[10077]: Traceback (most recent call last): Oct 14 16:30:59 volumio volumiobt[10077]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:30:59 volumio volumiobt[10077]: main() Oct 14 16:30:59 volumio volumiobt[10077]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:30:59 volumio volumiobt[10077]: asyncio.run(_run()) Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:30:59 volumio volumiobt[10077]: return runner.run(main) Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:30:59 volumio volumiobt[10077]: return self._loop.run_until_complete(task) Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:30:59 volumio volumiobt[10077]: return future.result() Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:30:59 volumio volumiobt[10077]: adapter_path = await find_adapter(bus) Oct 14 16:30:59 volumio volumiobt[10077]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:30:59 volumio volumiobt[10077]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:30:59 volumio volumiobt[10077]: raise Exception("Bluetooth adapter not found") Oct 14 16:30:59 volumio volumiobt[10077]: Exception: Bluetooth adapter not found Oct 14 16:30:59 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:30:59 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:30:59 volumio systemd[1]: volumiobt.service: Consumed 1.672s CPU time. Oct 14 16:30:59 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 16. Oct 14 16:30:59 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:59 volumio systemd[1]: volumiobt.service: Consumed 1.672s CPU time. Oct 14 16:30:59 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:30:59 volumio volumiobt[10079]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:30:59 volumio sudo[10080]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:30:59 volumio sudo[10080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:59 volumio sudo[10080]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:59 volumio sudo[10083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:30:59 volumio sudo[10083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:30:59 volumio sudo[10083]: pam_unix(sudo:session): session closed for user root Oct 14 16:30:59 volumio volumiobt[10085]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:30:59 volumio volumiobt[10089]: No default controller available Oct 14 16:31:00 volumio sshd[10081]: Accepted password for volumio from 10.0.0.219 port 49974 ssh2 Oct 14 16:31:00 volumio sshd[10081]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 16:31:00 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Oct 14 16:31:00 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 16:31:00 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 16:31:00 volumio systemd-logind[714]: New session 42 of user volumio. Oct 14 16:31:00 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 16:31:00 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 16:31:00 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 16:31:00 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Oct 14 16:31:00 volumio (systemd)[10092]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 16:31:00 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 16:31:00 volumio volumiobt[10107]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:31:00 volumio volumiobt[10108]: [165B blob data] Oct 14 16:31:00 volumio volumiobt[10108]: [bluetoothctl]> discoverable on Oct 14 16:31:00 volumio volumiobt[10108]: No default controller available Oct 14 16:31:00 volumio volumiobt[10108]: [bluetoothctl]> pairable on Oct 14 16:31:00 volumio volumiobt[10108]: No default controller available Oct 14 16:31:00 volumio volumiobt[10108]: [bluetoothctl]> Oct 14 16:31:00 volumio volumiobt[10109]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:31:01 volumio volumiobt[10111]: No agent is registered Oct 14 16:31:01 volumio volumiobt[10112]: INFO [BTSTART] Agent registered successfully. Oct 14 16:31:01 volumio volumiobt[10113]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:31:01 volumio systemd[10092]: Queued start job for default target default.target. Oct 14 16:31:01 volumio systemd[10092]: Created slice app.slice - User Application Slice. Oct 14 16:31:01 volumio systemd[10092]: Reached target paths.target - Paths. Oct 14 16:31:01 volumio systemd[10092]: Reached target timers.target - Timers. Oct 14 16:31:01 volumio systemd[10092]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Oct 14 16:31:01 volumio systemd[10092]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 16:31:01 volumio systemd[10092]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 16:31:01 volumio systemd[10092]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 16:31:01 volumio systemd[10092]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 16:31:01 volumio systemd[10092]: Reached target sockets.target - Sockets. Oct 14 16:31:01 volumio systemd[10092]: Reached target basic.target - Basic System. Oct 14 16:31:01 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Oct 14 16:31:01 volumio systemd[10092]: Started mpris-proxy.service - Bluetooth mpris proxy. Oct 14 16:31:01 volumio systemd[10092]: Reached target default.target - Main User Target. Oct 14 16:31:01 volumio systemd[10092]: Startup finished in 1.330s. Oct 14 16:31:01 volumio systemd[1]: Started session-42.scope - Session 42 of User volumio. Oct 14 16:31:01 volumio mpris-proxy[10115]: Can't get on session bus Oct 14 16:31:01 volumio systemd[10092]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:31:01 volumio systemd[10092]: mpris-proxy.service: Failed with result 'exit-code'. Oct 14 16:31:01 volumio sshd[10081]: pam_env(sshd:session): deprecated reading of user environment enabled Oct 14 16:31:02 volumio sudo[10129]: volumio : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cat /data/configuration/plugins.json Oct 14 16:31:02 volumio sudo[10129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:02 volumio sudo[10129]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:02 volumio volumiobt[10114]: 2026-10-14 16:31:02 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:31:02 volumio volumiobt[10114]: 2026-10-14 16:31:02 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:31:02 volumio volumiobt[10114]: 2026-10-14 16:31:02 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:31:02 volumio volumiobt[10114]: 2026-10-14 16:31:02 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:31:02 volumio volumiobt[10114]: Traceback (most recent call last): Oct 14 16:31:02 volumio volumiobt[10114]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:02 volumio volumiobt[10114]: asyncio.run(_run()) Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:02 volumio volumiobt[10114]: return runner.run(main) Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:02 volumio volumiobt[10114]: return self._loop.run_until_complete(task) Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:02 volumio volumiobt[10114]: return future.result() Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:02 volumio volumiobt[10114]: adapter_path = await find_adapter(bus) Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:02 volumio volumiobt[10114]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:02 volumio volumiobt[10114]: Exception: Bluetooth adapter not found Oct 14 16:31:02 volumio volumiobt[10114]: Traceback (most recent call last): Oct 14 16:31:02 volumio volumiobt[10114]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:31:02 volumio volumiobt[10114]: main() Oct 14 16:31:02 volumio volumiobt[10114]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:02 volumio volumiobt[10114]: asyncio.run(_run()) Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:02 volumio volumiobt[10114]: return runner.run(main) Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:02 volumio volumiobt[10114]: return self._loop.run_until_complete(task) Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:02 volumio volumiobt[10114]: return future.result() Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:02 volumio volumiobt[10114]: adapter_path = await find_adapter(bus) Oct 14 16:31:02 volumio volumiobt[10114]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:02 volumio volumiobt[10114]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:02 volumio volumiobt[10114]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:02 volumio volumiobt[10114]: Exception: Bluetooth adapter not found Oct 14 16:31:02 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:31:02 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:31:02 volumio systemd[1]: volumiobt.service: Consumed 1.713s CPU time. Oct 14 16:31:02 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 17. Oct 14 16:31:02 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:02 volumio systemd[1]: volumiobt.service: Consumed 1.713s CPU time. Oct 14 16:31:02 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:02 volumio volumiobt[10141]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:31:02 volumio sudo[10146]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:31:02 volumio sudo[10146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:02 volumio sudo[10146]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:02 volumio sudo[10148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:31:02 volumio sudo[10148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:02 volumio sudo[10148]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:02 volumio volumiobt[10150]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:31:02 volumio sshd[10125]: Received disconnect from 10.0.0.219 port 49974:11: disconnected by user Oct 14 16:31:02 volumio sshd[10125]: Disconnected from user volumio 10.0.0.219 port 49974 Oct 14 16:31:02 volumio sshd[10081]: pam_unix(sshd:session): session closed for user volumio Oct 14 16:31:02 volumio systemd[1]: session-42.scope: Deactivated successfully. Oct 14 16:31:02 volumio systemd[1]: session-42.scope: Consumed 1.141s CPU time. Oct 14 16:31:02 volumio systemd-logind[714]: Session 42 logged out. Waiting for processes to exit. Oct 14 16:31:02 volumio systemd-logind[714]: Removed session 42. Oct 14 16:31:03 volumio volumiobt[10153]: No default controller available Oct 14 16:31:04 volumio volumiobt[10155]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:31:04 volumio volumiobt[10156]: [83B blob data] Oct 14 16:31:04 volumio volumiobt[10156]: No default controller available Oct 14 16:31:04 volumio volumiobt[10156]: [bluetoothctl]> pairable on Oct 14 16:31:04 volumio volumiobt[10156]: No default controller available Oct 14 16:31:04 volumio volumiobt[10156]: [113B blob data] Oct 14 16:31:04 volumio volumiobt[10156]: [bluetoothctl]> Oct 14 16:31:04 volumio volumiobt[10157]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:31:04 volumio volumiobt[10159]: No agent is registered Oct 14 16:31:04 volumio volumiobt[10160]: INFO [BTSTART] Agent registered successfully. Oct 14 16:31:04 volumio volumiobt[10161]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:31:05 volumio volumiobt[10162]: 2026-10-14 16:31:05 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:31:05 volumio volumiobt[10162]: 2026-10-14 16:31:05 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:31:05 volumio volumiobt[10162]: 2026-10-14 16:31:05 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:31:05 volumio volumiobt[10162]: 2026-10-14 16:31:05 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:31:05 volumio volumiobt[10162]: Traceback (most recent call last): Oct 14 16:31:05 volumio volumiobt[10162]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:05 volumio volumiobt[10162]: asyncio.run(_run()) Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:05 volumio volumiobt[10162]: return runner.run(main) Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:05 volumio volumiobt[10162]: return self._loop.run_until_complete(task) Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:05 volumio volumiobt[10162]: return future.result() Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:05 volumio volumiobt[10162]: adapter_path = await find_adapter(bus) Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:05 volumio volumiobt[10162]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:05 volumio volumiobt[10162]: Exception: Bluetooth adapter not found Oct 14 16:31:05 volumio volumiobt[10162]: Traceback (most recent call last): Oct 14 16:31:05 volumio volumiobt[10162]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:31:05 volumio volumiobt[10162]: main() Oct 14 16:31:05 volumio volumiobt[10162]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:05 volumio volumiobt[10162]: asyncio.run(_run()) Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:05 volumio volumiobt[10162]: return runner.run(main) Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:05 volumio volumiobt[10162]: return self._loop.run_until_complete(task) Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:05 volumio volumiobt[10162]: return future.result() Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:05 volumio volumiobt[10162]: adapter_path = await find_adapter(bus) Oct 14 16:31:05 volumio volumiobt[10162]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:05 volumio volumiobt[10162]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:05 volumio volumiobt[10162]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:05 volumio volumiobt[10162]: Exception: Bluetooth adapter not found Oct 14 16:31:05 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:31:05 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:31:05 volumio systemd[1]: volumiobt.service: Consumed 1.598s CPU time. Oct 14 16:31:05 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 18. Oct 14 16:31:05 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:05 volumio systemd[1]: volumiobt.service: Consumed 1.598s CPU time. Oct 14 16:31:05 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:05 volumio volumiobt[10164]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:31:05 volumio sudo[10165]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:31:05 volumio sudo[10165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:05 volumio sudo[10165]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:05 volumio sudo[10167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:31:05 volumio sudo[10167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:05 volumio sudo[10167]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:05 volumio volumiobt[10169]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:31:06 volumio volumiobt[10172]: No default controller available Oct 14 16:31:07 volumio volumiobt[10178]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:31:07 volumio volumiobt[10183]: [83B blob data] Oct 14 16:31:07 volumio volumiobt[10183]: No default controller available Oct 14 16:31:07 volumio volumiobt[10183]: [bluetoothctl]> pairable on Oct 14 16:31:07 volumio volumiobt[10183]: No default controller available Oct 14 16:31:07 volumio volumiobt[10183]: [113B blob data] Oct 14 16:31:07 volumio volumiobt[10183]: [bluetoothctl]> Oct 14 16:31:07 volumio volumiobt[10190]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:31:07 volumio volumiobt[10192]: No agent is registered Oct 14 16:31:07 volumio volumiobt[10193]: INFO [BTSTART] Agent registered successfully. Oct 14 16:31:07 volumio volumiobt[10194]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:31:08 volumio volumiobt[10195]: 2026-10-14 16:31:08 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:31:08 volumio volumiobt[10195]: 2026-10-14 16:31:08 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:31:08 volumio volumiobt[10195]: 2026-10-14 16:31:08 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:31:08 volumio volumiobt[10195]: 2026-10-14 16:31:08 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:31:08 volumio volumiobt[10195]: Traceback (most recent call last): Oct 14 16:31:08 volumio volumiobt[10195]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:08 volumio volumiobt[10195]: asyncio.run(_run()) Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:08 volumio volumiobt[10195]: return runner.run(main) Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:08 volumio volumiobt[10195]: return self._loop.run_until_complete(task) Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:08 volumio volumiobt[10195]: return future.result() Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:08 volumio volumiobt[10195]: adapter_path = await find_adapter(bus) Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:08 volumio volumiobt[10195]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:08 volumio volumiobt[10195]: Exception: Bluetooth adapter not found Oct 14 16:31:08 volumio volumiobt[10195]: Traceback (most recent call last): Oct 14 16:31:08 volumio volumiobt[10195]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:31:08 volumio volumiobt[10195]: main() Oct 14 16:31:08 volumio volumiobt[10195]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:08 volumio volumiobt[10195]: asyncio.run(_run()) Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:08 volumio volumiobt[10195]: return runner.run(main) Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:08 volumio volumiobt[10195]: return self._loop.run_until_complete(task) Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:08 volumio volumiobt[10195]: return future.result() Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:08 volumio volumiobt[10195]: adapter_path = await find_adapter(bus) Oct 14 16:31:08 volumio volumiobt[10195]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:08 volumio volumiobt[10195]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:08 volumio volumiobt[10195]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:08 volumio volumiobt[10195]: Exception: Bluetooth adapter not found Oct 14 16:31:08 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:31:08 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:31:08 volumio systemd[1]: volumiobt.service: Consumed 1.668s CPU time. Oct 14 16:31:08 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 19. Oct 14 16:31:08 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:08 volumio systemd[1]: volumiobt.service: Consumed 1.668s CPU time. Oct 14 16:31:08 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:08 volumio volumiobt[10197]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:31:08 volumio sudo[10198]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:31:08 volumio sudo[10198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:08 volumio sudo[10198]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:08 volumio sudo[10200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:31:08 volumio sudo[10200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:08 volumio sudo[10200]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:09 volumio volumiobt[10202]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:31:09 volumio volumiobt[10205]: No default controller available Oct 14 16:31:10 volumio volumiobt[10207]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:31:10 volumio volumiobt[10208]: [83B blob data] Oct 14 16:31:10 volumio volumiobt[10208]: No default controller available Oct 14 16:31:10 volumio volumiobt[10208]: [bluetoothctl]> pairable on Oct 14 16:31:10 volumio volumiobt[10208]: No default controller available Oct 14 16:31:10 volumio volumiobt[10208]: [113B blob data] Oct 14 16:31:10 volumio volumiobt[10208]: [bluetoothctl]> Oct 14 16:31:10 volumio volumiobt[10209]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:31:10 volumio volumiobt[10211]: No agent is registered Oct 14 16:31:10 volumio volumiobt[10212]: INFO [BTSTART] Agent registered successfully. Oct 14 16:31:10 volumio volumiobt[10213]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:31:10 volumio volumiobt[10214]: 2026-10-14 16:31:10 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:31:10 volumio volumiobt[10214]: 2026-10-14 16:31:10 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:31:11 volumio volumiobt[10214]: 2026-10-14 16:31:11 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:31:11 volumio volumiobt[10214]: 2026-10-14 16:31:11 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:31:11 volumio volumiobt[10214]: Traceback (most recent call last): Oct 14 16:31:11 volumio volumiobt[10214]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:11 volumio volumiobt[10214]: asyncio.run(_run()) Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:11 volumio volumiobt[10214]: return runner.run(main) Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:11 volumio volumiobt[10214]: return self._loop.run_until_complete(task) Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:11 volumio volumiobt[10214]: return future.result() Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:11 volumio volumiobt[10214]: adapter_path = await find_adapter(bus) Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:11 volumio volumiobt[10214]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:11 volumio volumiobt[10214]: Exception: Bluetooth adapter not found Oct 14 16:31:11 volumio volumiobt[10214]: Traceback (most recent call last): Oct 14 16:31:11 volumio volumiobt[10214]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:31:11 volumio volumiobt[10214]: main() Oct 14 16:31:11 volumio volumiobt[10214]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:11 volumio volumiobt[10214]: asyncio.run(_run()) Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:11 volumio volumiobt[10214]: return runner.run(main) Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:11 volumio volumiobt[10214]: return self._loop.run_until_complete(task) Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:11 volumio volumiobt[10214]: return future.result() Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:11 volumio volumiobt[10214]: adapter_path = await find_adapter(bus) Oct 14 16:31:11 volumio volumiobt[10214]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:11 volumio volumiobt[10214]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:11 volumio volumiobt[10214]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:11 volumio volumiobt[10214]: Exception: Bluetooth adapter not found Oct 14 16:31:11 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:31:11 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:31:11 volumio systemd[1]: volumiobt.service: Consumed 1.194s CPU time. Oct 14 16:31:11 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 20. Oct 14 16:31:11 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:11 volumio systemd[1]: volumiobt.service: Consumed 1.194s CPU time. Oct 14 16:31:11 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:11 volumio volumiobt[10216]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:31:11 volumio sudo[10217]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:31:11 volumio sudo[10217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:11 volumio sudo[10217]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:11 volumio sudo[10219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:31:11 volumio sudo[10219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:11 volumio sudo[10219]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:11 volumio volumiobt[10221]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:31:11 volumio volumiobt[10224]: No default controller available Oct 14 16:31:12 volumio volumiobt[10226]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 14 16:31:12 volumio volumiobt[10227]: [83B blob data] Oct 14 16:31:12 volumio volumiobt[10227]: No default controller available Oct 14 16:31:12 volumio volumiobt[10227]: [bluetoothctl]> pairable on Oct 14 16:31:12 volumio volumiobt[10227]: No default controller available Oct 14 16:31:12 volumio volumiobt[10227]: [113B blob data] Oct 14 16:31:12 volumio volumiobt[10227]: [bluetoothctl]> Oct 14 16:31:12 volumio volumiobt[10228]: INFO [BTSTART] Registering Bluetooth agent... Oct 14 16:31:13 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Oct 14 16:31:13 volumio systemd[10092]: Activating special unit exit.target... Oct 14 16:31:13 volumio systemd[10092]: Stopped target default.target - Main User Target. Oct 14 16:31:13 volumio systemd[10092]: Stopped target basic.target - Basic System. Oct 14 16:31:13 volumio systemd[10092]: Stopped target paths.target - Paths. Oct 14 16:31:13 volumio systemd[10092]: Stopped target sockets.target - Sockets. Oct 14 16:31:13 volumio systemd[10092]: Stopped target timers.target - Timers. Oct 14 16:31:13 volumio systemd[10092]: Closed dirmngr.socket - GnuPG network certificate management daemon. Oct 14 16:31:13 volumio systemd[10092]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 16:31:13 volumio systemd[10092]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 16:31:13 volumio systemd[10092]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 16:31:13 volumio systemd[10092]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 16:31:13 volumio systemd[10092]: Removed slice app.slice - User Application Slice. Oct 14 16:31:13 volumio systemd[10092]: Reached target shutdown.target - Shutdown. Oct 14 16:31:13 volumio systemd[10092]: Finished systemd-exit.service - Exit the Session. Oct 14 16:31:13 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 16:31:13 volumio systemd[10092]: Reached target exit.target - Exit the Session. Oct 14 16:31:13 volumio (sd-pam)[10093]: pam_unix(systemd-user:session): session closed for user volumio Oct 14 16:31:13 volumio systemd[1]: user@1000.service: Deactivated successfully. Oct 14 16:31:13 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Oct 14 16:31:13 volumio systemd[1]: user@1000.service: Consumed 1.452s CPU time. Oct 14 16:31:13 volumio volumiobt[10230]: No agent is registered Oct 14 16:31:13 volumio volumiobt[10233]: INFO [BTSTART] Agent registered successfully. Oct 14 16:31:13 volumio volumiobt[10234]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 14 16:31:13 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 16:31:13 volumio volumio[9135]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 16:31:13 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Oct 14 16:31:13 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Oct 14 16:31:13 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 16:31:13 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Oct 14 16:31:13 volumio systemd[1]: user-1000.slice: Consumed 2.698s CPU time. Oct 14 16:31:14 volumio volumio[9135]: verbose: New Socket.io Connection to 10.0.0.163 from 10.0.0.238 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:31:14 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::volumioGetState Oct 14 16:31:14 volumio volumio[9135]: info: CorePlayQueue::getTrack 0 Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 14 16:31:14 volumio volumiobt[10235]: 2026-10-14 16:31:14 a2dp-agent [INFO] Connecting to system D-Bus Oct 14 16:31:14 volumio volumiobt[10235]: 2026-10-14 16:31:14 a2dp-agent [INFO] Connected to system D-Bus Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 16:31:14 volumio volumio[9135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Oct 14 16:31:14 volumio volumio[9135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 16:31:14 volumio volumiobt[10235]: 2026-10-14 16:31:14 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found Oct 14 16:31:14 volumio volumiobt[10235]: 2026-10-14 16:31:14 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found Oct 14 16:31:14 volumio volumiobt[10235]: Traceback (most recent call last): Oct 14 16:31:14 volumio volumiobt[10235]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:14 volumio volumiobt[10235]: asyncio.run(_run()) Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:14 volumio volumiobt[10235]: return runner.run(main) Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:14 volumio volumiobt[10235]: return self._loop.run_until_complete(task) Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:14 volumio volumiobt[10235]: return future.result() Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:14 volumio volumiobt[10235]: adapter_path = await find_adapter(bus) Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:14 volumio volumiobt[10235]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:14 volumio volumiobt[10235]: Exception: Bluetooth adapter not found Oct 14 16:31:14 volumio volumiobt[10235]: Traceback (most recent call last): Oct 14 16:31:14 volumio volumiobt[10235]: File "/bin/bt/a2dp-agent", line 234, in Oct 14 16:31:14 volumio volumiobt[10235]: main() Oct 14 16:31:14 volumio volumiobt[10235]: File "/bin/bt/a2dp-agent", line 225, in main Oct 14 16:31:14 volumio volumiobt[10235]: asyncio.run(_run()) Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run Oct 14 16:31:14 volumio volumiobt[10235]: return runner.run(main) Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run Oct 14 16:31:14 volumio volumio[9135]: TypeError: self.commandRouter.executeOnPlugin(...).then is not a function Oct 14 16:31:14 volumio volumio[9135]: at my_module.getMultiroom (/myvolumio/plugins/audio_interface/multiroom/index.js:13:14) Oct 14 16:31:14 volumio volumio[9135]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 14 16:31:14 volumio volumio[9135]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:811:45) Oct 14 16:31:14 volumio volumio[9135]: at Socket.emit (node:events:514:28) Oct 14 16:31:14 volumio volumio[9135]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 14 16:31:14 volumio volumio[9135]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 14 16:31:14 volumio volumio[9135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 16:31:14 volumio volumiobt[10235]: return self._loop.run_until_complete(task) Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete Oct 14 16:31:14 volumio volumiobt[10235]: return future.result() Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/bin/bt/a2dp-agent", line 175, in _run Oct 14 16:31:14 volumio volumiobt[10235]: adapter_path = await find_adapter(bus) Oct 14 16:31:14 volumio volumiobt[10235]: ^^^^^^^^^^^^^^^^^^^^^^^ Oct 14 16:31:14 volumio volumiobt[10235]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter Oct 14 16:31:14 volumio volumiobt[10235]: raise Exception("Bluetooth adapter not found") Oct 14 16:31:14 volumio volumiobt[10235]: Exception: Bluetooth adapter not found Oct 14 16:31:14 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Oct 14 16:31:14 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 14 16:31:14 volumio systemd[1]: volumiobt.service: Consumed 1.672s CPU time. Oct 14 16:31:14 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 21. Oct 14 16:31:14 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:14 volumio systemd[1]: volumiobt.service: Consumed 1.672s CPU time. Oct 14 16:31:14 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 14 16:31:14 volumio volumiobt[10261]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 14 16:31:14 volumio sudo[10262]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 14 16:31:14 volumio sudo[10262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:14 volumio sudo[10262]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:14 volumio sudo[10264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 14 16:31:14 volumio sudo[10264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:31:14 volumio sudo[10264]: pam_unix(sudo:session): session closed for user root Oct 14 16:31:14 volumio volumiobt[10266]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 14 16:31:15 volumio volumiobt[10269]: No default controller available Oct 14 16:31:15 volumio sudo[10273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 16:30' Oct 14 16:31:15 volumio sudo[10273]: 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"