Aug 27 13:01:00 volumio volumiobt[2377]: 2026-08-27 13:01:00 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:00 volumio volumiobt[2377]: 2026-08-27 13:01:00 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:00 volumio volumiobt[2377]: 2026-08-27 13:01:00 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:00 volumio volumiobt[2377]: 2026-08-27 13:01:00 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:00 volumio volumiobt[2377]: Traceback (most recent call last):
Aug 27 13:01:00 volumio volumiobt[2377]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:00 volumio volumiobt[2377]: asyncio.run(_run())
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:00 volumio volumiobt[2377]: return runner.run(main)
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:00 volumio volumiobt[2377]: return self._loop.run_until_complete(task)
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:00 volumio volumiobt[2377]: return future.result()
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:00 volumio volumiobt[2377]: adapter_path = await find_adapter(bus)
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:00 volumio volumiobt[2377]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:00 volumio volumiobt[2377]: Exception: Bluetooth adapter not found
Aug 27 13:01:00 volumio volumiobt[2377]: Traceback (most recent call last):
Aug 27 13:01:00 volumio volumiobt[2377]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:00 volumio volumiobt[2377]: main()
Aug 27 13:01:00 volumio volumiobt[2377]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:00 volumio volumiobt[2377]: asyncio.run(_run())
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:00 volumio volumiobt[2377]: return runner.run(main)
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:00 volumio volumiobt[2377]: return self._loop.run_until_complete(task)
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:00 volumio volumiobt[2377]: return future.result()
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:00 volumio volumiobt[2377]: adapter_path = await find_adapter(bus)
Aug 27 13:01:00 volumio volumiobt[2377]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:00 volumio volumiobt[2377]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:00 volumio volumiobt[2377]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:00 volumio volumiobt[2377]: Exception: Bluetooth adapter not found
Aug 27 13:01:00 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:00 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:00 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 21.
Aug 27 13:01:00 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:00 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:00 volumio volumiobt[2393]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:00 volumio sudo[2394]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:00 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:00 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:00 volumio sudo[2396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:00 volumio sudo[2396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:00 volumio sudo[2396]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:00 volumio volumiobt[2398]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:01 volumio volumiobt[2401]: No default controller available
Aug 27 13:01:02 volumio volumiobt[2403]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:02 volumio volumiobt[2404]: [83B blob data]
Aug 27 13:01:02 volumio volumiobt[2404]: No default controller available
Aug 27 13:01:02 volumio volumiobt[2404]: [bluetoothctl]> pairable on
Aug 27 13:01:02 volumio volumiobt[2404]: No default controller available
Aug 27 13:01:02 volumio volumiobt[2404]: [113B blob data]
Aug 27 13:01:02 volumio volumiobt[2404]: [bluetoothctl]>
Aug 27 13:01:02 volumio volumiobt[2405]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:02 volumio volumiobt[2407]: No agent is registered
Aug 27 13:01:02 volumio volumiobt[2408]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:02 volumio volumiobt[2409]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:02 volumio volumiobt[2410]: 2026-08-27 13:01:02 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:02 volumio volumiobt[2410]: 2026-08-27 13:01:02 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:02 volumio volumiobt[2410]: 2026-08-27 13:01:02 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:02 volumio volumiobt[2410]: 2026-08-27 13:01:02 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:02 volumio volumiobt[2410]: Traceback (most recent call last):
Aug 27 13:01:02 volumio volumiobt[2410]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:02 volumio volumiobt[2410]: asyncio.run(_run())
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:02 volumio volumiobt[2410]: return runner.run(main)
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:02 volumio volumiobt[2410]: return self._loop.run_until_complete(task)
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:02 volumio volumiobt[2410]: return future.result()
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:02 volumio volumiobt[2410]: adapter_path = await find_adapter(bus)
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:02 volumio volumiobt[2410]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:02 volumio volumiobt[2410]: Exception: Bluetooth adapter not found
Aug 27 13:01:02 volumio volumiobt[2410]: Traceback (most recent call last):
Aug 27 13:01:02 volumio volumiobt[2410]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:02 volumio volumiobt[2410]: main()
Aug 27 13:01:02 volumio volumiobt[2410]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:02 volumio volumiobt[2410]: asyncio.run(_run())
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:02 volumio volumiobt[2410]: return runner.run(main)
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:02 volumio volumiobt[2410]: return self._loop.run_until_complete(task)
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:02 volumio volumiobt[2410]: return future.result()
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:02 volumio volumiobt[2410]: adapter_path = await find_adapter(bus)
Aug 27 13:01:02 volumio volumiobt[2410]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:02 volumio volumiobt[2410]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:02 volumio volumiobt[2410]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:02 volumio volumiobt[2410]: Exception: Bluetooth adapter not found
Aug 27 13:01:02 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:02 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:03 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 22.
Aug 27 13:01:03 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:03 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:03 volumio volumiobt[2412]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:03 volumio sudo[2413]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:03 volumio sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:03 volumio sudo[2413]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:03 volumio sudo[2415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:03 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:03 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:03 volumio volumiobt[2417]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:03 volumio volumiobt[2420]: No default controller available
Aug 27 13:01:04 volumio volumiobt[2422]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:04 volumio volumiobt[2423]: [83B blob data]
Aug 27 13:01:04 volumio volumiobt[2423]: No default controller available
Aug 27 13:01:04 volumio volumiobt[2423]: [bluetoothctl]> pairable on
Aug 27 13:01:04 volumio volumiobt[2423]: No default controller available
Aug 27 13:01:04 volumio volumiobt[2423]: [113B blob data]
Aug 27 13:01:04 volumio volumiobt[2423]: [bluetoothctl]>
Aug 27 13:01:04 volumio volumiobt[2424]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:04 volumio volumiobt[2426]: No agent is registered
Aug 27 13:01:04 volumio volumiobt[2427]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:04 volumio volumiobt[2428]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:05 volumio volumiobt[2429]: 2026-08-27 13:01:05 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:05 volumio volumiobt[2429]: 2026-08-27 13:01:05 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:05 volumio volumiobt[2429]: 2026-08-27 13:01:05 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:05 volumio volumiobt[2429]: 2026-08-27 13:01:05 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:05 volumio volumiobt[2429]: Traceback (most recent call last):
Aug 27 13:01:05 volumio volumiobt[2429]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:05 volumio volumiobt[2429]: asyncio.run(_run())
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:05 volumio volumiobt[2429]: return runner.run(main)
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:05 volumio volumiobt[2429]: return self._loop.run_until_complete(task)
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:05 volumio volumiobt[2429]: return future.result()
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:05 volumio volumiobt[2429]: adapter_path = await find_adapter(bus)
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:05 volumio volumiobt[2429]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:05 volumio volumiobt[2429]: Exception: Bluetooth adapter not found
Aug 27 13:01:05 volumio volumiobt[2429]: Traceback (most recent call last):
Aug 27 13:01:05 volumio volumiobt[2429]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:05 volumio volumiobt[2429]: main()
Aug 27 13:01:05 volumio volumiobt[2429]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:05 volumio volumiobt[2429]: asyncio.run(_run())
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:05 volumio volumiobt[2429]: return runner.run(main)
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:05 volumio volumiobt[2429]: return self._loop.run_until_complete(task)
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:05 volumio volumiobt[2429]: return future.result()
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:05 volumio volumiobt[2429]: adapter_path = await find_adapter(bus)
Aug 27 13:01:05 volumio volumiobt[2429]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:05 volumio volumiobt[2429]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:05 volumio volumiobt[2429]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:05 volumio volumiobt[2429]: Exception: Bluetooth adapter not found
Aug 27 13:01:05 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:05 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:05 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 23.
Aug 27 13:01:05 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:05 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:05 volumio volumiobt[2431]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:05 volumio sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:05 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:05 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:05 volumio sudo[2434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:05 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:05 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:05 volumio volumiobt[2436]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:05 volumio volumiobt[2439]: No default controller available
Aug 27 13:01:06 volumio volumiobt[2441]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:06 volumio volumiobt[2442]: [83B blob data]
Aug 27 13:01:06 volumio volumiobt[2442]: No default controller available
Aug 27 13:01:06 volumio volumiobt[2442]: [bluetoothctl]> pairable on
Aug 27 13:01:06 volumio volumiobt[2442]: No default controller available
Aug 27 13:01:06 volumio volumiobt[2442]: [113B blob data]
Aug 27 13:01:06 volumio volumiobt[2442]: [bluetoothctl]>
Aug 27 13:01:06 volumio volumiobt[2443]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:06 volumio volumiobt[2445]: No agent is registered
Aug 27 13:01:06 volumio volumiobt[2446]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:06 volumio volumiobt[2447]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:07 volumio volumiobt[2448]: 2026-08-27 13:01:07 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:07 volumio volumiobt[2448]: 2026-08-27 13:01:07 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:07 volumio volumiobt[2448]: 2026-08-27 13:01:07 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:07 volumio volumiobt[2448]: 2026-08-27 13:01:07 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:07 volumio volumiobt[2448]: Traceback (most recent call last):
Aug 27 13:01:07 volumio volumiobt[2448]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:07 volumio volumiobt[2448]: asyncio.run(_run())
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:07 volumio volumiobt[2448]: return runner.run(main)
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:07 volumio volumiobt[2448]: return self._loop.run_until_complete(task)
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:07 volumio volumiobt[2448]: return future.result()
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:07 volumio volumiobt[2448]: adapter_path = await find_adapter(bus)
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:07 volumio volumiobt[2448]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:07 volumio volumiobt[2448]: Exception: Bluetooth adapter not found
Aug 27 13:01:07 volumio volumiobt[2448]: Traceback (most recent call last):
Aug 27 13:01:07 volumio volumiobt[2448]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:07 volumio volumiobt[2448]: main()
Aug 27 13:01:07 volumio volumiobt[2448]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:07 volumio volumiobt[2448]: asyncio.run(_run())
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:07 volumio volumiobt[2448]: return runner.run(main)
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:07 volumio volumiobt[2448]: return self._loop.run_until_complete(task)
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:07 volumio volumiobt[2448]: return future.result()
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:07 volumio volumiobt[2448]: adapter_path = await find_adapter(bus)
Aug 27 13:01:07 volumio volumiobt[2448]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:07 volumio volumiobt[2448]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:07 volumio volumiobt[2448]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:07 volumio volumiobt[2448]: Exception: Bluetooth adapter not found
Aug 27 13:01:07 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:07 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:08 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 24.
Aug 27 13:01:08 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:08 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:08 volumio volumiobt[2450]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:08 volumio sudo[2451]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:08 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:08 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:08 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:08 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:08 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:08 volumio volumiobt[2455]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:08 volumio volumiobt[2458]: No default controller available
Aug 27 13:01:09 volumio volumiobt[2460]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:09 volumio volumiobt[2461]: [83B blob data]
Aug 27 13:01:09 volumio volumiobt[2461]: No default controller available
Aug 27 13:01:09 volumio volumiobt[2461]: [bluetoothctl]> pairable on
Aug 27 13:01:09 volumio volumiobt[2461]: No default controller available
Aug 27 13:01:09 volumio volumiobt[2461]: [113B blob data]
Aug 27 13:01:09 volumio volumiobt[2461]: [bluetoothctl]>
Aug 27 13:01:09 volumio volumiobt[2462]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:09 volumio volumiobt[2465]: No agent is registered
Aug 27 13:01:09 volumio volumiobt[2466]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:09 volumio volumiobt[2467]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:10 volumio volumiobt[2468]: 2026-08-27 13:01:10 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:10 volumio volumiobt[2468]: 2026-08-27 13:01:10 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:10 volumio volumiobt[2468]: 2026-08-27 13:01:10 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:10 volumio volumiobt[2468]: 2026-08-27 13:01:10 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:10 volumio volumiobt[2468]: Traceback (most recent call last):
Aug 27 13:01:10 volumio volumiobt[2468]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:10 volumio volumiobt[2468]: asyncio.run(_run())
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:10 volumio volumiobt[2468]: return runner.run(main)
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:10 volumio volumiobt[2468]: return self._loop.run_until_complete(task)
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:10 volumio volumiobt[2468]: return future.result()
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:10 volumio volumiobt[2468]: adapter_path = await find_adapter(bus)
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:10 volumio volumiobt[2468]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:10 volumio volumiobt[2468]: Exception: Bluetooth adapter not found
Aug 27 13:01:10 volumio volumiobt[2468]: Traceback (most recent call last):
Aug 27 13:01:10 volumio volumiobt[2468]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:10 volumio volumiobt[2468]: main()
Aug 27 13:01:10 volumio volumiobt[2468]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:10 volumio volumiobt[2468]: asyncio.run(_run())
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:10 volumio volumiobt[2468]: return runner.run(main)
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:10 volumio volumiobt[2468]: return self._loop.run_until_complete(task)
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:10 volumio volumiobt[2468]: return future.result()
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:10 volumio volumiobt[2468]: adapter_path = await find_adapter(bus)
Aug 27 13:01:10 volumio volumiobt[2468]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:10 volumio volumiobt[2468]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:10 volumio volumiobt[2468]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:10 volumio volumiobt[2468]: Exception: Bluetooth adapter not found
Aug 27 13:01:10 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:10 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:10 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 25.
Aug 27 13:01:10 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:10 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:10 volumio volumiobt[2475]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:10 volumio sudo[2476]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:10 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:10 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:10 volumio sudo[2482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:10 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:10 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:10 volumio volumiobt[2485]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:10 volumio volumiobt[2492]: No default controller available
Aug 27 13:01:11 volumio volumiobt[2495]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:11 volumio volumiobt[2496]: [83B blob data]
Aug 27 13:01:11 volumio volumiobt[2496]: No default controller available
Aug 27 13:01:11 volumio volumiobt[2496]: [bluetoothctl]> pairable on
Aug 27 13:01:11 volumio volumiobt[2496]: No default controller available
Aug 27 13:01:11 volumio volumiobt[2496]: [113B blob data]
Aug 27 13:01:11 volumio volumiobt[2496]: [bluetoothctl]>
Aug 27 13:01:11 volumio volumiobt[2497]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:11 volumio volumiobt[2499]: No agent is registered
Aug 27 13:01:11 volumio volumiobt[2500]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:11 volumio volumiobt[2501]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:12 volumio volumiobt[2502]: 2026-08-27 13:01:12 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:12 volumio volumiobt[2502]: 2026-08-27 13:01:12 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:12 volumio volumiobt[2502]: 2026-08-27 13:01:12 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:12 volumio volumiobt[2502]: 2026-08-27 13:01:12 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:12 volumio volumiobt[2502]: Traceback (most recent call last):
Aug 27 13:01:12 volumio volumiobt[2502]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:12 volumio volumiobt[2502]: asyncio.run(_run())
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:12 volumio volumiobt[2502]: return runner.run(main)
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:12 volumio volumiobt[2502]: return self._loop.run_until_complete(task)
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:12 volumio volumiobt[2502]: return future.result()
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:12 volumio volumiobt[2502]: adapter_path = await find_adapter(bus)
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:12 volumio volumiobt[2502]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:12 volumio volumiobt[2502]: Exception: Bluetooth adapter not found
Aug 27 13:01:12 volumio volumiobt[2502]: Traceback (most recent call last):
Aug 27 13:01:12 volumio volumiobt[2502]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:12 volumio volumiobt[2502]: main()
Aug 27 13:01:12 volumio volumiobt[2502]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:12 volumio volumiobt[2502]: asyncio.run(_run())
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:12 volumio volumiobt[2502]: return runner.run(main)
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:12 volumio volumiobt[2502]: return self._loop.run_until_complete(task)
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:12 volumio volumiobt[2502]: return future.result()
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:12 volumio volumiobt[2502]: adapter_path = await find_adapter(bus)
Aug 27 13:01:12 volumio volumiobt[2502]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:12 volumio volumiobt[2502]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:12 volumio volumiobt[2502]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:12 volumio volumiobt[2502]: Exception: Bluetooth adapter not found
Aug 27 13:01:12 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:12 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:13 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 26.
Aug 27 13:01:13 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:13 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:13 volumio volumiobt[2504]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:13 volumio sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:13 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:13 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:13 volumio sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:13 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:13 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:13 volumio volumiobt[2509]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:13 volumio volumiobt[2512]: No default controller available
Aug 27 13:01:14 volumio volumiobt[2514]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:14 volumio volumiobt[2515]: [83B blob data]
Aug 27 13:01:14 volumio volumiobt[2515]: No default controller available
Aug 27 13:01:14 volumio volumiobt[2515]: [bluetoothctl]> pairable on
Aug 27 13:01:14 volumio volumiobt[2515]: No default controller available
Aug 27 13:01:14 volumio volumiobt[2515]: [bluetoothctl]>
Aug 27 13:01:14 volumio volumiobt[2516]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:14 volumio volumiobt[2518]: No agent is registered
Aug 27 13:01:14 volumio volumiobt[2519]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:14 volumio volumiobt[2520]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:15 volumio volumiobt[2521]: 2026-08-27 13:01:15 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:15 volumio volumiobt[2521]: 2026-08-27 13:01:15 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:15 volumio volumiobt[2521]: 2026-08-27 13:01:15 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:15 volumio volumiobt[2521]: 2026-08-27 13:01:15 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:15 volumio volumiobt[2521]: Traceback (most recent call last):
Aug 27 13:01:15 volumio volumiobt[2521]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:15 volumio volumiobt[2521]: asyncio.run(_run())
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:15 volumio volumiobt[2521]: return runner.run(main)
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:15 volumio volumiobt[2521]: return self._loop.run_until_complete(task)
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:15 volumio volumiobt[2521]: return future.result()
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:15 volumio volumiobt[2521]: adapter_path = await find_adapter(bus)
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:15 volumio volumiobt[2521]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:15 volumio volumiobt[2521]: Exception: Bluetooth adapter not found
Aug 27 13:01:15 volumio volumiobt[2521]: Traceback (most recent call last):
Aug 27 13:01:15 volumio volumiobt[2521]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:15 volumio volumiobt[2521]: main()
Aug 27 13:01:15 volumio volumiobt[2521]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:15 volumio volumiobt[2521]: asyncio.run(_run())
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:15 volumio volumiobt[2521]: return runner.run(main)
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:15 volumio volumiobt[2521]: return self._loop.run_until_complete(task)
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:15 volumio volumiobt[2521]: return future.result()
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:15 volumio volumiobt[2521]: adapter_path = await find_adapter(bus)
Aug 27 13:01:15 volumio volumiobt[2521]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:15 volumio volumiobt[2521]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:15 volumio volumiobt[2521]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:15 volumio volumiobt[2521]: Exception: Bluetooth adapter not found
Aug 27 13:01:15 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:15 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:15 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 27.
Aug 27 13:01:15 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:15 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:15 volumio volumiobt[2523]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:15 volumio sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:15 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:15 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:15 volumio sudo[2526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:15 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:15 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:15 volumio volumiobt[2528]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:15 volumio volumiobt[2531]: No default controller available
Aug 27 13:01:16 volumio volumiobt[2533]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:16 volumio volumiobt[2534]: [83B blob data]
Aug 27 13:01:16 volumio volumiobt[2534]: No default controller available
Aug 27 13:01:16 volumio volumiobt[2534]: [bluetoothctl]> pairable on
Aug 27 13:01:16 volumio volumiobt[2534]: No default controller available
Aug 27 13:01:16 volumio volumiobt[2534]: [113B blob data]
Aug 27 13:01:16 volumio volumiobt[2534]: [bluetoothctl]>
Aug 27 13:01:16 volumio volumiobt[2535]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:17 volumio volumiobt[2537]: No agent is registered
Aug 27 13:01:17 volumio volumiobt[2538]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:17 volumio volumiobt[2539]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:17 volumio volumiobt[2540]: 2026-08-27 13:01:17 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:17 volumio volumiobt[2540]: 2026-08-27 13:01:17 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:17 volumio volumiobt[2540]: 2026-08-27 13:01:17 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:17 volumio volumiobt[2540]: 2026-08-27 13:01:17 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:17 volumio volumiobt[2540]: Traceback (most recent call last):
Aug 27 13:01:17 volumio volumiobt[2540]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:17 volumio volumiobt[2540]: asyncio.run(_run())
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:17 volumio volumiobt[2540]: return runner.run(main)
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:17 volumio volumiobt[2540]: return self._loop.run_until_complete(task)
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:17 volumio volumiobt[2540]: return future.result()
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:17 volumio volumiobt[2540]: adapter_path = await find_adapter(bus)
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:17 volumio volumiobt[2540]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:17 volumio volumiobt[2540]: Exception: Bluetooth adapter not found
Aug 27 13:01:17 volumio volumiobt[2540]: Traceback (most recent call last):
Aug 27 13:01:17 volumio volumiobt[2540]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:17 volumio volumiobt[2540]: main()
Aug 27 13:01:17 volumio volumiobt[2540]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:17 volumio volumiobt[2540]: asyncio.run(_run())
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:17 volumio volumiobt[2540]: return runner.run(main)
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:17 volumio volumiobt[2540]: return self._loop.run_until_complete(task)
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:17 volumio volumiobt[2540]: return future.result()
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:17 volumio volumiobt[2540]: adapter_path = await find_adapter(bus)
Aug 27 13:01:17 volumio volumiobt[2540]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:17 volumio volumiobt[2540]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:17 volumio volumiobt[2540]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:17 volumio volumiobt[2540]: Exception: Bluetooth adapter not found
Aug 27 13:01:17 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:17 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:18 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 28.
Aug 27 13:01:18 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:18 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:18 volumio volumiobt[2542]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:18 volumio sudo[2543]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:18 volumio sudo[2543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:18 volumio sudo[2543]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:18 volumio sudo[2545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:18 volumio sudo[2545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:18 volumio sudo[2545]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:18 volumio volumiobt[2547]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:18 volumio volumiobt[2550]: No default controller available
Aug 27 13:01:19 volumio volumiobt[2552]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:19 volumio volumiobt[2553]: [165B blob data]
Aug 27 13:01:19 volumio volumiobt[2553]: [bluetoothctl]> discoverable on
Aug 27 13:01:19 volumio volumiobt[2553]: No default controller available
Aug 27 13:01:19 volumio volumiobt[2553]: [bluetoothctl]> pairable on
Aug 27 13:01:19 volumio volumiobt[2553]: No default controller available
Aug 27 13:01:19 volumio volumiobt[2553]: [bluetoothctl]>
Aug 27 13:01:19 volumio volumiobt[2554]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:19 volumio volumiobt[2556]: No agent is registered
Aug 27 13:01:19 volumio volumiobt[2557]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:19 volumio volumiobt[2558]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:20 volumio volumiobt[2559]: 2026-08-27 13:01:20 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:20 volumio volumiobt[2559]: 2026-08-27 13:01:20 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:20 volumio volumiobt[2559]: 2026-08-27 13:01:20 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:20 volumio volumiobt[2559]: 2026-08-27 13:01:20 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:20 volumio volumiobt[2559]: Traceback (most recent call last):
Aug 27 13:01:20 volumio volumiobt[2559]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:20 volumio volumiobt[2559]: asyncio.run(_run())
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:20 volumio volumiobt[2559]: return runner.run(main)
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:20 volumio volumiobt[2559]: return self._loop.run_until_complete(task)
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:20 volumio volumiobt[2559]: return future.result()
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:20 volumio volumiobt[2559]: adapter_path = await find_adapter(bus)
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:20 volumio volumiobt[2559]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:20 volumio volumiobt[2559]: Exception: Bluetooth adapter not found
Aug 27 13:01:20 volumio volumiobt[2559]: Traceback (most recent call last):
Aug 27 13:01:20 volumio volumiobt[2559]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:20 volumio volumiobt[2559]: main()
Aug 27 13:01:20 volumio volumiobt[2559]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:20 volumio volumiobt[2559]: asyncio.run(_run())
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:20 volumio volumiobt[2559]: return runner.run(main)
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:20 volumio volumiobt[2559]: return self._loop.run_until_complete(task)
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:20 volumio volumiobt[2559]: return future.result()
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:20 volumio volumiobt[2559]: adapter_path = await find_adapter(bus)
Aug 27 13:01:20 volumio volumiobt[2559]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:20 volumio volumiobt[2559]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:20 volumio volumiobt[2559]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:20 volumio volumiobt[2559]: Exception: Bluetooth adapter not found
Aug 27 13:01:20 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:20 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:20 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 29.
Aug 27 13:01:20 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:20 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:20 volumio volumiobt[2561]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:20 volumio sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:20 volumio sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:20 volumio sudo[2562]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:20 volumio sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:20 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:20 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:20 volumio volumiobt[2566]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:20 volumio volumiobt[2569]: No default controller available
Aug 27 13:01:21 volumio volumiobt[2585]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:21 volumio volumiobt[2586]: [83B blob data]
Aug 27 13:01:21 volumio volumiobt[2586]: No default controller available
Aug 27 13:01:21 volumio volumiobt[2586]: [bluetoothctl]> pairable on
Aug 27 13:01:21 volumio volumiobt[2586]: No default controller available
Aug 27 13:01:21 volumio volumiobt[2586]: [bluetoothctl]>
Aug 27 13:01:21 volumio volumiobt[2587]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:21 volumio volumiobt[2589]: No agent is registered
Aug 27 13:01:21 volumio volumiobt[2590]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:21 volumio volumiobt[2591]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:22 volumio volumiobt[2592]: 2026-08-27 13:01:22 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:22 volumio volumiobt[2592]: 2026-08-27 13:01:22 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:22 volumio volumiobt[2592]: 2026-08-27 13:01:22 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:22 volumio volumiobt[2592]: 2026-08-27 13:01:22 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:22 volumio volumiobt[2592]: Traceback (most recent call last):
Aug 27 13:01:22 volumio volumiobt[2592]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:22 volumio volumiobt[2592]: asyncio.run(_run())
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:22 volumio volumiobt[2592]: return runner.run(main)
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:22 volumio volumiobt[2592]: return self._loop.run_until_complete(task)
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:22 volumio volumiobt[2592]: return future.result()
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:22 volumio volumiobt[2592]: adapter_path = await find_adapter(bus)
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:22 volumio volumiobt[2592]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:22 volumio volumiobt[2592]: Exception: Bluetooth adapter not found
Aug 27 13:01:22 volumio volumiobt[2592]: Traceback (most recent call last):
Aug 27 13:01:22 volumio volumiobt[2592]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:22 volumio volumiobt[2592]: main()
Aug 27 13:01:22 volumio volumiobt[2592]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:22 volumio volumiobt[2592]: asyncio.run(_run())
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:22 volumio volumiobt[2592]: return runner.run(main)
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:22 volumio volumiobt[2592]: return self._loop.run_until_complete(task)
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:22 volumio volumiobt[2592]: return future.result()
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:22 volumio volumiobt[2592]: adapter_path = await find_adapter(bus)
Aug 27 13:01:22 volumio volumiobt[2592]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:22 volumio volumiobt[2592]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:22 volumio volumiobt[2592]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:22 volumio volumiobt[2592]: Exception: Bluetooth adapter not found
Aug 27 13:01:22 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:22 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:23 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 30.
Aug 27 13:01:23 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:23 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:23 volumio volumiobt[2594]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:23 volumio sudo[2595]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:23 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:23 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:23 volumio sudo[2597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:23 volumio sudo[2597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:23 volumio sudo[2597]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:23 volumio volumiobt[2599]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:23 volumio volumiobt[2602]: No default controller available
Aug 27 13:01:24 volumio volumiobt[2604]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:24 volumio volumiobt[2605]: [83B blob data]
Aug 27 13:01:24 volumio volumiobt[2605]: No default controller available
Aug 27 13:01:24 volumio volumiobt[2605]: [bluetoothctl]> pairable on
Aug 27 13:01:24 volumio volumiobt[2605]: No default controller available
Aug 27 13:01:24 volumio volumiobt[2605]: [bluetoothctl]>
Aug 27 13:01:24 volumio volumiobt[2606]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:24 volumio volumiobt[2608]: No agent is registered
Aug 27 13:01:24 volumio volumiobt[2609]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:24 volumio volumiobt[2610]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:24 volumio volumiobt[2611]: 2026-08-27 13:01:24 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:25 volumio volumiobt[2611]: 2026-08-27 13:01:25 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:25 volumio volumiobt[2611]: 2026-08-27 13:01:25 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:25 volumio volumiobt[2611]: 2026-08-27 13:01:25 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:25 volumio volumiobt[2611]: Traceback (most recent call last):
Aug 27 13:01:25 volumio volumiobt[2611]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:25 volumio volumiobt[2611]: asyncio.run(_run())
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:25 volumio volumiobt[2611]: return runner.run(main)
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:25 volumio volumiobt[2611]: return self._loop.run_until_complete(task)
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:25 volumio volumiobt[2611]: return future.result()
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:25 volumio volumiobt[2611]: adapter_path = await find_adapter(bus)
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:25 volumio volumiobt[2611]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:25 volumio volumiobt[2611]: Exception: Bluetooth adapter not found
Aug 27 13:01:25 volumio volumiobt[2611]: Traceback (most recent call last):
Aug 27 13:01:25 volumio volumiobt[2611]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:25 volumio volumiobt[2611]: main()
Aug 27 13:01:25 volumio volumiobt[2611]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:25 volumio volumiobt[2611]: asyncio.run(_run())
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:25 volumio volumiobt[2611]: return runner.run(main)
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:25 volumio volumiobt[2611]: return self._loop.run_until_complete(task)
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:25 volumio volumiobt[2611]: return future.result()
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:25 volumio volumiobt[2611]: adapter_path = await find_adapter(bus)
Aug 27 13:01:25 volumio volumiobt[2611]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:25 volumio volumiobt[2611]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:25 volumio volumiobt[2611]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:25 volumio volumiobt[2611]: Exception: Bluetooth adapter not found
Aug 27 13:01:25 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:25 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:25 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 31.
Aug 27 13:01:25 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:25 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:25 volumio volumiobt[2613]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:25 volumio sudo[2614]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:25 volumio sudo[2614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:25 volumio sudo[2614]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:25 volumio sudo[2616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:25 volumio sudo[2616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:25 volumio sudo[2616]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:25 volumio volumiobt[2618]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:25 volumio volumiobt[2621]: No default controller available
Aug 27 13:01:26 volumio volumiobt[2623]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:26 volumio volumiobt[2624]: [83B blob data]
Aug 27 13:01:26 volumio volumiobt[2624]: No default controller available
Aug 27 13:01:26 volumio volumiobt[2624]: [bluetoothctl]> pairable on
Aug 27 13:01:26 volumio volumiobt[2624]: No default controller available
Aug 27 13:01:26 volumio volumiobt[2624]: [113B blob data]
Aug 27 13:01:26 volumio volumiobt[2624]: [bluetoothctl]>
Aug 27 13:01:26 volumio volumiobt[2625]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:26 volumio volumiobt[2627]: No agent is registered
Aug 27 13:01:26 volumio volumiobt[2628]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:26 volumio volumiobt[2629]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:27 volumio volumiobt[2630]: 2026-08-27 13:01:27 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:27 volumio volumiobt[2630]: 2026-08-27 13:01:27 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:27 volumio volumiobt[2630]: 2026-08-27 13:01:27 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:27 volumio volumiobt[2630]: 2026-08-27 13:01:27 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:27 volumio volumiobt[2630]: Traceback (most recent call last):
Aug 27 13:01:27 volumio volumiobt[2630]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:27 volumio volumiobt[2630]: asyncio.run(_run())
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:27 volumio volumiobt[2630]: return runner.run(main)
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:27 volumio volumiobt[2630]: return self._loop.run_until_complete(task)
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:27 volumio volumiobt[2630]: return future.result()
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:27 volumio volumiobt[2630]: adapter_path = await find_adapter(bus)
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:27 volumio volumiobt[2630]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:27 volumio volumiobt[2630]: Exception: Bluetooth adapter not found
Aug 27 13:01:27 volumio volumiobt[2630]: Traceback (most recent call last):
Aug 27 13:01:27 volumio volumiobt[2630]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:27 volumio volumiobt[2630]: main()
Aug 27 13:01:27 volumio volumiobt[2630]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:27 volumio volumiobt[2630]: asyncio.run(_run())
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:27 volumio volumiobt[2630]: return runner.run(main)
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:27 volumio volumiobt[2630]: return self._loop.run_until_complete(task)
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:27 volumio volumiobt[2630]: return future.result()
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:27 volumio volumiobt[2630]: adapter_path = await find_adapter(bus)
Aug 27 13:01:27 volumio volumiobt[2630]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:27 volumio volumiobt[2630]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:27 volumio volumiobt[2630]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:27 volumio volumiobt[2630]: Exception: Bluetooth adapter not found
Aug 27 13:01:27 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:27 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:27 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 32.
Aug 27 13:01:27 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:27 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:27 volumio volumiobt[2632]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:27 volumio sudo[2633]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:27 volumio sudo[2633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:27 volumio sudo[2633]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:27 volumio sudo[2635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:27 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:27 volumio sudo[2635]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:27 volumio volumiobt[2637]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:27 volumio volumiobt[2640]: No default controller available
Aug 27 13:01:28 volumio volumiobt[2642]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:28 volumio volumiobt[2643]: [83B blob data]
Aug 27 13:01:28 volumio volumiobt[2643]: No default controller available
Aug 27 13:01:28 volumio volumiobt[2643]: [bluetoothctl]> pairable on
Aug 27 13:01:28 volumio volumiobt[2643]: No default controller available
Aug 27 13:01:28 volumio volumiobt[2643]: [113B blob data]
Aug 27 13:01:28 volumio volumiobt[2643]: [bluetoothctl]>
Aug 27 13:01:28 volumio volumiobt[2644]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:28 volumio volumiobt[2646]: No agent is registered
Aug 27 13:01:28 volumio volumiobt[2647]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:28 volumio volumiobt[2648]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:29 volumio volumiobt[2649]: 2026-08-27 13:01:29 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:29 volumio volumiobt[2649]: 2026-08-27 13:01:29 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:29 volumio volumiobt[2649]: 2026-08-27 13:01:29 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:29 volumio volumiobt[2649]: 2026-08-27 13:01:29 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:29 volumio volumiobt[2649]: Traceback (most recent call last):
Aug 27 13:01:29 volumio volumiobt[2649]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:29 volumio volumiobt[2649]: asyncio.run(_run())
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:29 volumio volumiobt[2649]: return runner.run(main)
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:29 volumio volumiobt[2649]: return self._loop.run_until_complete(task)
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:29 volumio volumiobt[2649]: return future.result()
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:29 volumio volumiobt[2649]: adapter_path = await find_adapter(bus)
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:29 volumio volumiobt[2649]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:29 volumio volumiobt[2649]: Exception: Bluetooth adapter not found
Aug 27 13:01:29 volumio volumiobt[2649]: Traceback (most recent call last):
Aug 27 13:01:29 volumio volumiobt[2649]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:29 volumio volumiobt[2649]: main()
Aug 27 13:01:29 volumio volumiobt[2649]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:29 volumio volumiobt[2649]: asyncio.run(_run())
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:29 volumio volumiobt[2649]: return runner.run(main)
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:29 volumio volumiobt[2649]: return self._loop.run_until_complete(task)
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:29 volumio volumiobt[2649]: return future.result()
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:29 volumio volumiobt[2649]: adapter_path = await find_adapter(bus)
Aug 27 13:01:29 volumio volumiobt[2649]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:29 volumio volumiobt[2649]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:29 volumio volumiobt[2649]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:29 volumio volumiobt[2649]: Exception: Bluetooth adapter not found
Aug 27 13:01:29 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:29 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:30 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 33.
Aug 27 13:01:30 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:30 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:30 volumio volumiobt[2651]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:30 volumio sudo[2652]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:30 volumio sudo[2652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:30 volumio sudo[2652]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:30 volumio sudo[2654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:30 volumio sudo[2654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:30 volumio sudo[2654]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:30 volumio volumiobt[2656]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:30 volumio volumiobt[2659]: No default controller available
Aug 27 13:01:31 volumio volumiobt[2675]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:31 volumio volumiobt[2676]: [165B blob data]
Aug 27 13:01:31 volumio volumiobt[2676]: [bluetoothctl]> discoverable on
Aug 27 13:01:31 volumio volumiobt[2676]: No default controller available
Aug 27 13:01:31 volumio volumiobt[2676]: [bluetoothctl]> pairable on
Aug 27 13:01:31 volumio volumiobt[2676]: No default controller available
Aug 27 13:01:31 volumio volumiobt[2676]: [bluetoothctl]>
Aug 27 13:01:31 volumio volumiobt[2677]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:31 volumio volumiobt[2679]: No agent is registered
Aug 27 13:01:31 volumio volumiobt[2680]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:31 volumio volumiobt[2681]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:31 volumio volumiobt[2682]: 2026-08-27 13:01:31 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:31 volumio volumiobt[2682]: 2026-08-27 13:01:31 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:31 volumio volumiobt[2682]: 2026-08-27 13:01:31 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:31 volumio volumiobt[2682]: 2026-08-27 13:01:31 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:31 volumio volumiobt[2682]: Traceback (most recent call last):
Aug 27 13:01:31 volumio volumiobt[2682]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:31 volumio volumiobt[2682]: asyncio.run(_run())
Aug 27 13:01:31 volumio volumiobt[2682]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:31 volumio volumiobt[2682]: return runner.run(main)
Aug 27 13:01:31 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:31 volumio volumiobt[2682]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:31 volumio volumiobt[2682]: return self._loop.run_until_complete(task)
Aug 27 13:01:31 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:31 volumio volumiobt[2682]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:31 volumio volumiobt[2682]: return future.result()
Aug 27 13:01:31 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^
Aug 27 13:01:31 volumio volumiobt[2682]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:31 volumio volumiobt[2682]: adapter_path = await find_adapter(bus)
Aug 27 13:01:31 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:31 volumio volumiobt[2682]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:31 volumio volumiobt[2682]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:31 volumio volumiobt[2682]: Exception: Bluetooth adapter not found
Aug 27 13:01:32 volumio volumiobt[2682]: Traceback (most recent call last):
Aug 27 13:01:32 volumio volumiobt[2682]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:32 volumio volumiobt[2682]: main()
Aug 27 13:01:32 volumio volumiobt[2682]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:32 volumio volumiobt[2682]: asyncio.run(_run())
Aug 27 13:01:32 volumio volumiobt[2682]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:32 volumio volumiobt[2682]: return runner.run(main)
Aug 27 13:01:32 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:32 volumio volumiobt[2682]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:32 volumio volumiobt[2682]: return self._loop.run_until_complete(task)
Aug 27 13:01:32 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:32 volumio volumiobt[2682]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:32 volumio volumiobt[2682]: return future.result()
Aug 27 13:01:32 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^
Aug 27 13:01:32 volumio volumiobt[2682]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:32 volumio volumiobt[2682]: adapter_path = await find_adapter(bus)
Aug 27 13:01:32 volumio volumiobt[2682]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:32 volumio volumiobt[2682]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:32 volumio volumiobt[2682]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:32 volumio volumiobt[2682]: Exception: Bluetooth adapter not found
Aug 27 13:01:32 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:32 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:32 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 34.
Aug 27 13:01:32 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:32 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:32 volumio volumiobt[2684]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:32 volumio sudo[2685]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:32 volumio sudo[2685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:32 volumio sudo[2685]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:32 volumio sudo[2687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:32 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:32 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:32 volumio volumiobt[2689]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:32 volumio volumiobt[2692]: No default controller available
Aug 27 13:01:33 volumio volumiobt[2694]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:33 volumio volumiobt[2695]: [83B blob data]
Aug 27 13:01:33 volumio volumiobt[2695]: No default controller available
Aug 27 13:01:33 volumio volumiobt[2695]: [bluetoothctl]> pairable on
Aug 27 13:01:33 volumio volumiobt[2695]: No default controller available
Aug 27 13:01:33 volumio volumiobt[2695]: [113B blob data]
Aug 27 13:01:33 volumio volumiobt[2695]: [bluetoothctl]>
Aug 27 13:01:33 volumio volumiobt[2696]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:33 volumio volumiobt[2698]: No agent is registered
Aug 27 13:01:33 volumio volumiobt[2699]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:33 volumio volumiobt[2700]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:34 volumio volumiobt[2701]: 2026-08-27 13:01:34 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:34 volumio volumiobt[2701]: 2026-08-27 13:01:34 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:34 volumio volumiobt[2701]: 2026-08-27 13:01:34 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:34 volumio volumiobt[2701]: 2026-08-27 13:01:34 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:34 volumio volumiobt[2701]: Traceback (most recent call last):
Aug 27 13:01:34 volumio volumiobt[2701]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:34 volumio volumiobt[2701]: asyncio.run(_run())
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:34 volumio volumiobt[2701]: return runner.run(main)
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:34 volumio volumiobt[2701]: return self._loop.run_until_complete(task)
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:34 volumio volumiobt[2701]: return future.result()
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:34 volumio volumiobt[2701]: adapter_path = await find_adapter(bus)
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:34 volumio volumiobt[2701]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:34 volumio volumiobt[2701]: Exception: Bluetooth adapter not found
Aug 27 13:01:34 volumio volumiobt[2701]: Traceback (most recent call last):
Aug 27 13:01:34 volumio volumiobt[2701]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:34 volumio volumiobt[2701]: main()
Aug 27 13:01:34 volumio volumiobt[2701]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:34 volumio volumiobt[2701]: asyncio.run(_run())
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:34 volumio volumiobt[2701]: return runner.run(main)
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:34 volumio volumiobt[2701]: return self._loop.run_until_complete(task)
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:34 volumio volumiobt[2701]: return future.result()
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:34 volumio volumiobt[2701]: adapter_path = await find_adapter(bus)
Aug 27 13:01:34 volumio volumiobt[2701]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:34 volumio volumiobt[2701]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:34 volumio volumiobt[2701]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:34 volumio volumiobt[2701]: Exception: Bluetooth adapter not found
Aug 27 13:01:34 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:34 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:34 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 35.
Aug 27 13:01:34 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:34 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:34 volumio volumiobt[2704]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:34 volumio sudo[2705]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:34 volumio sudo[2705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:34 volumio sudo[2705]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:34 volumio sudo[2707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:34 volumio sudo[2707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:34 volumio sudo[2707]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:34 volumio volumiobt[2709]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:35 volumio volumiobt[2712]: No default controller available
Aug 27 13:01:35 volumio sudo[2717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 27 13:01:35 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:35 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:35 volumio sudo[2718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 27 13:01:35 volumio sudo[2718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:35 volumio sudo[2718]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:35 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.0.222 from 192.168.0.54 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Aug 27 13:01:36 volumio volumiobt[2723]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:36 volumio volumiobt[2724]: [83B blob data]
Aug 27 13:01:36 volumio volumiobt[2724]: No default controller available
Aug 27 13:01:36 volumio volumiobt[2724]: [bluetoothctl]> pairable on
Aug 27 13:01:36 volumio volumiobt[2724]: No default controller available
Aug 27 13:01:36 volumio volumiobt[2724]: [113B blob data]
Aug 27 13:01:36 volumio volumiobt[2724]: [bluetoothctl]>
Aug 27 13:01:36 volumio volumiobt[2725]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:36 volumio volumiobt[2727]: No agent is registered
Aug 27 13:01:36 volumio volumiobt[2728]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:36 volumio volumiobt[2729]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:36 volumio sudo[2732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 27 13:01:36 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:36 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:36 volumio sudo[2734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 27 13:01:36 volumio sudo[2734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:36 volumio sudo[2734]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:36 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.0.222 from 192.168.0.54 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Aug 27 13:01:36 volumio volumiobt[2730]: 2026-08-27 13:01:36 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:36 volumio volumiobt[2730]: 2026-08-27 13:01:36 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:36 volumio volumiobt[2730]: 2026-08-27 13:01:36 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:36 volumio volumiobt[2730]: 2026-08-27 13:01:36 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:36 volumio volumiobt[2730]: Traceback (most recent call last):
Aug 27 13:01:36 volumio volumiobt[2730]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:36 volumio volumiobt[2730]: asyncio.run(_run())
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:36 volumio volumiobt[2730]: return runner.run(main)
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:36 volumio volumiobt[2730]: return self._loop.run_until_complete(task)
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:36 volumio volumiobt[2730]: return future.result()
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:36 volumio volumiobt[2730]: adapter_path = await find_adapter(bus)
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:36 volumio volumiobt[2730]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:36 volumio volumiobt[2730]: Exception: Bluetooth adapter not found
Aug 27 13:01:36 volumio volumiobt[2730]: Traceback (most recent call last):
Aug 27 13:01:36 volumio volumiobt[2730]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:36 volumio volumiobt[2730]: main()
Aug 27 13:01:36 volumio volumiobt[2730]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:36 volumio volumiobt[2730]: asyncio.run(_run())
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:36 volumio volumiobt[2730]: return runner.run(main)
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:36 volumio volumiobt[2730]: return self._loop.run_until_complete(task)
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:36 volumio volumiobt[2730]: return future.result()
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:36 volumio volumiobt[2730]: adapter_path = await find_adapter(bus)
Aug 27 13:01:36 volumio volumiobt[2730]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:36 volumio volumiobt[2730]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:36 volumio volumiobt[2730]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:36 volumio volumiobt[2730]: Exception: Bluetooth adapter not found
Aug 27 13:01:36 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:36 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:37 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 27 13:01:37 volumio volumio[975]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Aug 27 13:01:37 volumio volumio[975]: info: Received Get System Info
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 13:01:37 volumio volumio[975]: info: Discovery: Getting this device information
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:37 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 13:01:37 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 36.
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:37 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:37 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:37 volumio volumio[975]: info: Listing playlists
Aug 27 13:01:37 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:37 volumio volumiobt[2740]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 27 13:01:37 volumio sudo[2741]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:37 volumio sudo[2741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:37 volumio sudo[2741]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:37 volumio sudo[2743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:37 volumio sudo[2743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:37 volumio sudo[2743]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:37 volumio volumiobt[2745]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Aug 27 13:01:37 volumio volumiobt[2748]: No default controller available
Aug 27 13:01:37 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:37 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 27 13:01:38 volumio volumio[975]: info: Received Get System Info
Aug 27 13:01:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 13:01:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 13:01:38 volumio volumio[975]: info: Discovery: Getting this device information
Aug 27 13:01:38 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:38 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 13:01:38 volumio volumiobt[2751]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:38 volumio volumiobt[2752]: [83B blob data]
Aug 27 13:01:38 volumio volumiobt[2752]: No default controller available
Aug 27 13:01:38 volumio volumiobt[2752]: [bluetoothctl]> pairable on
Aug 27 13:01:38 volumio volumiobt[2752]: No default controller available
Aug 27 13:01:38 volumio volumiobt[2752]: [113B blob data]
Aug 27 13:01:38 volumio volumiobt[2752]: [bluetoothctl]>
Aug 27 13:01:38 volumio volumiobt[2753]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:38 volumio volumiobt[2755]: No agent is registered
Aug 27 13:01:38 volumio volumiobt[2756]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:38 volumio volumiobt[2757]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Aug 27 13:01:39 volumio volumiobt[2758]: 2026-08-27 13:01:39 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:39 volumio volumiobt[2758]: 2026-08-27 13:01:39 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:39 volumio volumiobt[2758]: 2026-08-27 13:01:39 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:39 volumio volumiobt[2758]: 2026-08-27 13:01:39 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:39 volumio volumiobt[2758]: Traceback (most recent call last):
Aug 27 13:01:39 volumio volumiobt[2758]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:39 volumio volumiobt[2758]: asyncio.run(_run())
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:39 volumio volumiobt[2758]: return runner.run(main)
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:39 volumio volumiobt[2758]: return self._loop.run_until_complete(task)
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:39 volumio volumiobt[2758]: return future.result()
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:39 volumio volumiobt[2758]: adapter_path = await find_adapter(bus)
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:39 volumio volumiobt[2758]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:39 volumio volumiobt[2758]: Exception: Bluetooth adapter not found
Aug 27 13:01:39 volumio volumiobt[2758]: Traceback (most recent call last):
Aug 27 13:01:39 volumio volumiobt[2758]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:39 volumio volumiobt[2758]: main()
Aug 27 13:01:39 volumio volumiobt[2758]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:39 volumio volumiobt[2758]: asyncio.run(_run())
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:39 volumio volumiobt[2758]: return runner.run(main)
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:39 volumio volumiobt[2758]: return self._loop.run_until_complete(task)
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:39 volumio volumiobt[2758]: return future.result()
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:39 volumio volumiobt[2758]: adapter_path = await find_adapter(bus)
Aug 27 13:01:39 volumio volumiobt[2758]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:39 volumio volumiobt[2758]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:39 volumio volumiobt[2758]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:39 volumio volumiobt[2758]: Exception: Bluetooth adapter not found
Aug 27 13:01:39 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:39 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 27 13:01:39 volumio volumio[975]: info: Received Get System Info
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 13:01:39 volumio volumio[975]: info: Discovery: Getting this device information
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 13:01:39 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 37.
Aug 27 13:01:39 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:39 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:39 volumio volumio[975]: info: Executing endpoint metavolumio
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 27 13:01:39 volumio volumiobt[2760]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:39 volumio sudo[2761]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:39 volumio sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:39 volumio sudo[2761]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:39 volumio volumio[975]: info: Executing endpoint metavolumio
Aug 27 13:01:39 volumio sudo[2763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:39 volumio sudo[2763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:39 volumio sudo[2763]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:39 volumio volumiobt[2765]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 27 13:01:39 volumio volumio[975]: info: Executing endpoint metavolumio
Aug 27 13:01:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 27 13:01:39 volumio volumiobt[2768]: No default controller available
Aug 27 13:01:41 volumio volumiobt[2770]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:41 volumio volumiobt[2771]: [83B blob data]
Aug 27 13:01:41 volumio volumiobt[2771]: No default controller available
Aug 27 13:01:41 volumio volumiobt[2771]: [bluetoothctl]> pairable on
Aug 27 13:01:41 volumio volumiobt[2771]: No default controller available
Aug 27 13:01:41 volumio volumiobt[2771]: [113B blob data]
Aug 27 13:01:41 volumio volumiobt[2771]: [bluetoothctl]>
Aug 27 13:01:41 volumio volumiobt[2772]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:41 volumio volumiobt[2778]: No agent is registered
Aug 27 13:01:41 volumio volumiobt[2783]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:41 volumio volumiobt[2784]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:41 volumio volumiobt[2785]: 2026-08-27 13:01:41 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:41 volumio volumiobt[2785]: 2026-08-27 13:01:41 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:41 volumio volumiobt[2785]: 2026-08-27 13:01:41 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:41 volumio volumiobt[2785]: 2026-08-27 13:01:41 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:41 volumio volumiobt[2785]: Traceback (most recent call last):
Aug 27 13:01:41 volumio volumiobt[2785]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:41 volumio volumiobt[2785]: asyncio.run(_run())
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:41 volumio volumiobt[2785]: return runner.run(main)
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:41 volumio volumiobt[2785]: return self._loop.run_until_complete(task)
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:41 volumio volumiobt[2785]: return future.result()
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:41 volumio volumiobt[2785]: adapter_path = await find_adapter(bus)
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:41 volumio volumiobt[2785]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:41 volumio volumiobt[2785]: Exception: Bluetooth adapter not found
Aug 27 13:01:41 volumio volumiobt[2785]: Traceback (most recent call last):
Aug 27 13:01:41 volumio volumiobt[2785]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:41 volumio volumiobt[2785]: main()
Aug 27 13:01:41 volumio volumiobt[2785]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:41 volumio volumiobt[2785]: asyncio.run(_run())
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:41 volumio volumiobt[2785]: return runner.run(main)
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:41 volumio volumiobt[2785]: return self._loop.run_until_complete(task)
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:41 volumio volumiobt[2785]: return future.result()
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:41 volumio volumiobt[2785]: adapter_path = await find_adapter(bus)
Aug 27 13:01:41 volumio volumiobt[2785]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:41 volumio volumiobt[2785]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:41 volumio volumiobt[2785]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:41 volumio volumiobt[2785]: Exception: Bluetooth adapter not found
Aug 27 13:01:41 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:41 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:42 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 38.
Aug 27 13:01:42 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:42 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:42 volumio volumiobt[2793]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:42 volumio sudo[2794]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:42 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:42 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:42 volumio sudo[2796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:42 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:42 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:42 volumio volumiobt[2798]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:42 volumio volumiobt[2801]: No default controller available
Aug 27 13:01:43 volumio volumiobt[2803]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:43 volumio volumiobt[2804]: [165B blob data]
Aug 27 13:01:43 volumio volumiobt[2804]: [bluetoothctl]> discoverable on
Aug 27 13:01:43 volumio volumiobt[2804]: No default controller available
Aug 27 13:01:43 volumio volumiobt[2804]: [bluetoothctl]> pairable on
Aug 27 13:01:43 volumio volumiobt[2804]: No default controller available
Aug 27 13:01:43 volumio volumiobt[2804]: [bluetoothctl]>
Aug 27 13:01:43 volumio volumiobt[2805]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:43 volumio volumiobt[2807]: No agent is registered
Aug 27 13:01:43 volumio volumiobt[2808]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:43 volumio volumiobt[2809]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:43 volumio volumiobt[2810]: 2026-08-27 13:01:43 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:43 volumio volumiobt[2810]: 2026-08-27 13:01:43 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:43 volumio volumiobt[2810]: 2026-08-27 13:01:43 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:44 volumio volumiobt[2810]: 2026-08-27 13:01:43 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:44 volumio volumiobt[2810]: Traceback (most recent call last):
Aug 27 13:01:44 volumio volumiobt[2810]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:44 volumio volumiobt[2810]: asyncio.run(_run())
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:44 volumio volumiobt[2810]: return runner.run(main)
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:44 volumio volumiobt[2810]: return self._loop.run_until_complete(task)
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:44 volumio volumiobt[2810]: return future.result()
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:44 volumio volumiobt[2810]: adapter_path = await find_adapter(bus)
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:44 volumio volumiobt[2810]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:44 volumio volumiobt[2810]: Exception: Bluetooth adapter not found
Aug 27 13:01:44 volumio volumiobt[2810]: Traceback (most recent call last):
Aug 27 13:01:44 volumio volumiobt[2810]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:44 volumio volumiobt[2810]: main()
Aug 27 13:01:44 volumio volumiobt[2810]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:44 volumio volumiobt[2810]: asyncio.run(_run())
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:44 volumio volumiobt[2810]: return runner.run(main)
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:44 volumio volumiobt[2810]: return self._loop.run_until_complete(task)
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:44 volumio volumiobt[2810]: return future.result()
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:44 volumio volumiobt[2810]: adapter_path = await find_adapter(bus)
Aug 27 13:01:44 volumio volumiobt[2810]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:44 volumio volumiobt[2810]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:44 volumio volumiobt[2810]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:44 volumio volumiobt[2810]: Exception: Bluetooth adapter not found
Aug 27 13:01:44 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:44 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:44 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 39.
Aug 27 13:01:44 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:44 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:44 volumio volumiobt[2812]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:44 volumio sudo[2813]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:44 volumio sudo[2813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:44 volumio sudo[2813]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:44 volumio sudo[2815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:44 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:44 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:44 volumio volumiobt[2817]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:44 volumio volumiobt[2820]: No default controller available
Aug 27 13:01:45 volumio volumiobt[2822]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:45 volumio volumiobt[2823]: [83B blob data]
Aug 27 13:01:45 volumio volumiobt[2823]: No default controller available
Aug 27 13:01:45 volumio volumiobt[2823]: [bluetoothctl]> pairable on
Aug 27 13:01:45 volumio volumiobt[2823]: No default controller available
Aug 27 13:01:45 volumio volumiobt[2823]: [bluetoothctl]>
Aug 27 13:01:45 volumio volumiobt[2824]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:45 volumio volumiobt[2826]: No agent is registered
Aug 27 13:01:45 volumio volumiobt[2827]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:45 volumio volumiobt[2828]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:46 volumio volumiobt[2829]: 2026-08-27 13:01:46 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:46 volumio volumiobt[2829]: 2026-08-27 13:01:46 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:46 volumio volumiobt[2829]: 2026-08-27 13:01:46 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:46 volumio volumiobt[2829]: 2026-08-27 13:01:46 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:46 volumio volumiobt[2829]: Traceback (most recent call last):
Aug 27 13:01:46 volumio volumiobt[2829]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:46 volumio volumiobt[2829]: asyncio.run(_run())
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:46 volumio volumiobt[2829]: return runner.run(main)
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:46 volumio volumiobt[2829]: return self._loop.run_until_complete(task)
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:46 volumio volumiobt[2829]: return future.result()
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:46 volumio volumiobt[2829]: adapter_path = await find_adapter(bus)
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:46 volumio volumiobt[2829]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:46 volumio volumiobt[2829]: Exception: Bluetooth adapter not found
Aug 27 13:01:46 volumio volumiobt[2829]: Traceback (most recent call last):
Aug 27 13:01:46 volumio volumiobt[2829]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:46 volumio volumiobt[2829]: main()
Aug 27 13:01:46 volumio volumiobt[2829]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:46 volumio volumiobt[2829]: asyncio.run(_run())
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:46 volumio volumiobt[2829]: return runner.run(main)
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:46 volumio volumiobt[2829]: return self._loop.run_until_complete(task)
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:46 volumio volumiobt[2829]: return future.result()
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:46 volumio volumiobt[2829]: adapter_path = await find_adapter(bus)
Aug 27 13:01:46 volumio volumiobt[2829]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:46 volumio volumiobt[2829]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:46 volumio volumiobt[2829]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:46 volumio volumiobt[2829]: Exception: Bluetooth adapter not found
Aug 27 13:01:46 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:46 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:46 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 40.
Aug 27 13:01:46 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:46 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:46 volumio volumiobt[2831]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:46 volumio sudo[2832]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:46 volumio sudo[2832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:46 volumio sudo[2832]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:46 volumio sudo[2834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:46 volumio sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:46 volumio sudo[2834]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:46 volumio volumiobt[2836]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:46 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 27 13:01:46 volumio volumiobt[2839]: No default controller available
Aug 27 13:01:47 volumio volumio[975]: error: Failed request for metavolumio API
Aug 27 13:01:47 volumio volumio[975]: error: Failed request for metavolumio API
Aug 27 13:01:47 volumio volumio[975]: error: Failed request for metavolumio API
Aug 27 13:01:47 volumio volumiobt[2841]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:47 volumio volumiobt[2842]: [83B blob data]
Aug 27 13:01:47 volumio volumiobt[2842]: No default controller available
Aug 27 13:01:47 volumio volumiobt[2842]: [bluetoothctl]> pairable on
Aug 27 13:01:47 volumio volumiobt[2842]: No default controller available
Aug 27 13:01:47 volumio volumiobt[2842]: [bluetoothctl]>
Aug 27 13:01:47 volumio volumiobt[2843]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:47 volumio volumiobt[2845]: No agent is registered
Aug 27 13:01:47 volumio volumiobt[2846]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:47 volumio volumiobt[2847]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:48 volumio volumiobt[2848]: 2026-08-27 13:01:48 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:48 volumio volumiobt[2848]: 2026-08-27 13:01:48 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:48 volumio volumiobt[2848]: 2026-08-27 13:01:48 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:48 volumio volumiobt[2848]: 2026-08-27 13:01:48 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:48 volumio volumiobt[2848]: Traceback (most recent call last):
Aug 27 13:01:48 volumio volumiobt[2848]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:48 volumio volumiobt[2848]: asyncio.run(_run())
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:48 volumio volumiobt[2848]: return runner.run(main)
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:48 volumio volumiobt[2848]: return self._loop.run_until_complete(task)
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:48 volumio volumiobt[2848]: return future.result()
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:48 volumio volumiobt[2848]: adapter_path = await find_adapter(bus)
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:48 volumio volumiobt[2848]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:48 volumio volumiobt[2848]: Exception: Bluetooth adapter not found
Aug 27 13:01:48 volumio volumiobt[2848]: Traceback (most recent call last):
Aug 27 13:01:48 volumio volumiobt[2848]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:48 volumio volumiobt[2848]: main()
Aug 27 13:01:48 volumio volumiobt[2848]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:48 volumio volumiobt[2848]: asyncio.run(_run())
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:48 volumio volumiobt[2848]: return runner.run(main)
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:48 volumio volumiobt[2848]: return self._loop.run_until_complete(task)
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:48 volumio volumiobt[2848]: return future.result()
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:48 volumio volumiobt[2848]: adapter_path = await find_adapter(bus)
Aug 27 13:01:48 volumio volumiobt[2848]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:48 volumio volumiobt[2848]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:48 volumio volumiobt[2848]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:48 volumio volumiobt[2848]: Exception: Bluetooth adapter not found
Aug 27 13:01:48 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:48 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:49 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 41.
Aug 27 13:01:49 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:49 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:49 volumio volumiobt[2850]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:49 volumio sudo[2851]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:49 volumio sudo[2851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:49 volumio sudo[2851]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:49 volumio sudo[2853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:49 volumio sudo[2853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:49 volumio sudo[2853]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:49 volumio volumiobt[2855]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:49 volumio volumiobt[2858]: No default controller available
Aug 27 13:01:50 volumio volumiobt[2860]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:50 volumio volumiobt[2861]: [83B blob data]
Aug 27 13:01:50 volumio volumiobt[2861]: No default controller available
Aug 27 13:01:50 volumio volumiobt[2861]: [bluetoothctl]> pairable on
Aug 27 13:01:50 volumio volumiobt[2861]: No default controller available
Aug 27 13:01:50 volumio volumiobt[2861]: [bluetoothctl]>
Aug 27 13:01:50 volumio volumiobt[2862]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:50 volumio volumiobt[2864]: No agent is registered
Aug 27 13:01:50 volumio volumiobt[2865]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:50 volumio volumiobt[2866]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:50 volumio kernel: usb 1-1.2: new high-speed USB device number 4 using dwc_otg
Aug 27 13:01:50 volumio kernel: usb 1-1.2: New USB device found, idVendor=2772, idProduct=0226, bcdDevice= 1.10
Aug 27 13:01:50 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Aug 27 13:01:50 volumio kernel: usb 1-1.2: Product: DAC Box DS2 USB Audio 2.0
Aug 27 13:01:50 volumio kernel: usb 1-1.2: Manufacturer: Project
Aug 27 13:01:50 volumio kernel: usb 1-1.2: 1:1: cannot set freq 768000 (v2/v3): err -71
Aug 27 13:01:50 volumio kernel: usb 1-1.2: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41)
Aug 27 13:01:50 volumio kernel: usb 1-1.2: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: failed to get current value for ch 1 (-71)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: failed to get current value for ch 2 (-71)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: failed to get current value for ch 0 (-71)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usbcore: registered new interface driver snd-usb-audio
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10)
Aug 27 13:01:51 volumio (udev-worker)[2868]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99.
Aug 27 13:01:51 volumio volumiobt[2867]: 2026-08-27 13:01:51 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:51 volumio volumiobt[2867]: 2026-08-27 13:01:51 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:51 volumio volumiobt[2867]: 2026-08-27 13:01:51 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:51 volumio volumiobt[2867]: 2026-08-27 13:01:51 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:51 volumio volumiobt[2867]: Traceback (most recent call last):
Aug 27 13:01:51 volumio volumiobt[2867]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:51 volumio volumiobt[2867]: asyncio.run(_run())
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:51 volumio volumiobt[2867]: return runner.run(main)
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:51 volumio volumiobt[2867]: return self._loop.run_until_complete(task)
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:51 volumio volumiobt[2867]: return future.result()
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:51 volumio volumiobt[2867]: adapter_path = await find_adapter(bus)
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:51 volumio volumiobt[2867]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:51 volumio volumiobt[2867]: Exception: Bluetooth adapter not found
Aug 27 13:01:51 volumio volumiobt[2867]: Traceback (most recent call last):
Aug 27 13:01:51 volumio volumiobt[2867]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:51 volumio volumiobt[2867]: main()
Aug 27 13:01:51 volumio volumiobt[2867]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:51 volumio volumiobt[2867]: asyncio.run(_run())
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:51 volumio volumiobt[2867]: return runner.run(main)
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:51 volumio volumiobt[2867]: return self._loop.run_until_complete(task)
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:51 volumio volumiobt[2867]: return future.result()
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:51 volumio volumiobt[2867]: adapter_path = await find_adapter(bus)
Aug 27 13:01:51 volumio volumiobt[2867]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:51 volumio volumiobt[2867]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:51 volumio volumiobt[2867]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:51 volumio volumiobt[2867]: Exception: Bluetooth adapter not found
Aug 27 13:01:51 volumio volumio[975]: info:
Aug 27 13:01:51 volumio volumio[975]: ---------------------------- USB Audio Device Attached
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Aug 27 13:01:51 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:51 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::Close All Modals sent
Aug 27 13:01:51 volumio volumio[975]: info: Preparing to save Alsa Options, stopping services first
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:51 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::volumioPause
Aug 27 13:01:51 volumio volumio[975]: info: CoreStateMachine::pause
Aug 27 13:01:51 volumio volumio[975]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DAC Box DS2 USB Audio 2.0","alsacard":"D20"},"i2s":false}
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Aug 27 13:01:51 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 42.
Aug 27 13:01:51 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:51 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Aug 27 13:01:51 volumio volumiobt[2902]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:51 volumio sudo[2905]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:51 volumio sudo[2905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:51 volumio sudo[2905]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:51 volumio sudo[2908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:51 volumio sudo[2908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:51 volumio sudo[2908]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:51 volumio volumiobt[2910]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:51 volumio volumio[975]: amixer: Mixer sysdefault:5 load error: Protocol error
Aug 27 13:01:51 volumio volumiobt[2913]: No default controller available
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Aug 27 13:01:51 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 5 Name: DAC Box DS2 USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Aug 27 13:01:51 volumio volumio[975]: info: Disabling external Volume Control
Aug 27 13:01:51 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 27 13:01:51 volumio volumio[975]: info: Preparing to generate the ALSA configuration file
Aug 27 13:01:52 volumio volumio[975]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 27 13:01:52 volumio volumio[975]: info: Reading ALSA contributions from plugins.
Aug 27 13:01:52 volumio volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer sysdefault:5 load error: Protocol error
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Aug 27 13:01:52 volumio volumio[975]: amixer: Mixer sysdefault:5 load error: Protocol error
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat
Aug 27 13:01:52 volumio volumio[975]: info: VolumeController:: Volume=undefined Mute =false
Aug 27 13:01:52 volumio volumio[975]: info: CoreStateMachine::pushState
Aug 27 13:01:52 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::volumioPushState
Aug 27 13:01:52 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device
Aug 27 13:01:52 volumio volumio[975]: info: MRS: Pushing multiroomSync output
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:52 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:52 volumio volumio[975]: info: Asound.conf file unchanged, so no further update is needed
Aug 27 13:01:52 volumio volumio[975]: info: Output device has changed, restarting MPD
Aug 27 13:01:52 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 13:01:52 volumio sudo[2927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 27 13:01:52 volumio volumiobt[2934]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:52 volumio volumiobt[2935]: [83B blob data]
Aug 27 13:01:52 volumio volumiobt[2935]: No default controller available
Aug 27 13:01:52 volumio volumiobt[2935]: [bluetoothctl]> pairable on
Aug 27 13:01:52 volumio volumiobt[2935]: No default controller available
Aug 27 13:01:52 volumio volumiobt[2935]: [113B blob data]
Aug 27 13:01:52 volumio sudo[2927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:52 volumio volumiobt[2935]: [bluetoothctl]>
Aug 27 13:01:52 volumio volumiobt[2937]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:52 volumio sudo[2931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 27 13:01:52 volumio sudo[2931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:52 volumio sudo[2927]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:52 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 27 13:01:52 volumio volumio[975]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:52 volumio volumiobt[2941]: No agent is registered
Aug 27 13:01:52 volumio volumio[975]: info: QobuzConnect: setDeactiveState invoked
Aug 27 13:01:52 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:52 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:52 volumio vtcs[1973]: [2026-08-27 13:01:52.987] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE
Aug 27 13:01:52 volumio vtcs[1973]: [2026-08-27 13:01:52.988] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected
Aug 27 13:01:52 volumio volumiobt[2942]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:53 volumio volumiobt[2943]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 27 13:01:53 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 27 13:01:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 27 13:01:53 volumio systemd[1]: mpd.service: Consumed 3.356s CPU time.
Aug 27 13:01:53 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 27 13:01:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 27 13:01:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 27 13:01:53 volumio sudo[2947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 27 13:01:53 volumio sudo[2947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 27 13:01:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 27 13:01:53 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Aug 27 13:01:53 volumio systemd[1]: vtcs.service: Killing process 1979 (vtcs) with signal SIGKILL.
Aug 27 13:01:53 volumio systemd[1]: vtcs.service: Deactivated successfully.
Aug 27 13:01:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Aug 27 13:01:53 volumio sudo[2953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 27 13:01:53 volumio sudo[2953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:53 volumio sudo[2947]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:53 volumio sudo[2954]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 27 13:01:53 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 27 13:01:53 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:53 volumio volumio[975]: info: MPD Permissions set
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio sudo[2961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Aug 27 13:01:53 volumio sudo[2961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:53 volumio sudo[2953]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 13:01:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:53 volumio sudo[2961]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:53 volumio volumiobt[2944]: 2026-08-27 13:01:53 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:53 volumio volumiobt[2944]: 2026-08-27 13:01:53 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:53 volumio volumio[975]: info: Starting Shairport Sync
Aug 27 13:01:53 volumio volumiobt[2944]: 2026-08-27 13:01:53 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:53 volumio volumiobt[2944]: 2026-08-27 13:01:53 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:53 volumio volumiobt[2944]: Traceback (most recent call last):
Aug 27 13:01:53 volumio volumiobt[2944]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:53 volumio volumiobt[2944]: asyncio.run(_run())
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:53 volumio volumiobt[2944]: return runner.run(main)
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:53 volumio volumiobt[2944]: return self._loop.run_until_complete(task)
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:53 volumio volumiobt[2944]: return future.result()
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:53 volumio volumiobt[2944]: adapter_path = await find_adapter(bus)
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:53 volumio volumiobt[2944]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:53 volumio volumiobt[2944]: Exception: Bluetooth adapter not found
Aug 27 13:01:53 volumio volumiobt[2944]: Traceback (most recent call last):
Aug 27 13:01:53 volumio volumiobt[2944]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:53 volumio volumiobt[2944]: main()
Aug 27 13:01:53 volumio volumiobt[2944]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:53 volumio volumiobt[2944]: asyncio.run(_run())
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:53 volumio volumiobt[2944]: return runner.run(main)
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:53 volumio volumiobt[2944]: return self._loop.run_until_complete(task)
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:53 volumio volumiobt[2944]: return future.result()
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:53 volumio volumiobt[2944]: adapter_path = await find_adapter(bus)
Aug 27 13:01:53 volumio volumiobt[2944]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:53 volumio volumiobt[2944]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:53 volumio volumiobt[2944]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:53 volumio volumiobt[2944]: Exception: Bluetooth adapter not found
Aug 27 13:01:53 volumio volumio[975]: info: Preparing to generate the ALSA configuration file
Aug 27 13:01:53 volumio sudo[2971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Aug 27 13:01:53 volumio sudo[2971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:54 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:54 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:54 volumio systemd[1]: volumiobt.service: Consumed 1.034s CPU time.
Aug 27 13:01:54 volumio sudo[2973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 27 13:01:54 volumio sudo[2973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:54 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Aug 27 13:01:54 volumio qobuz-connect[1941]: 20260827 13:01:54.080 [1941.1941] INFO SampleApp: Stopping Local configuration server
Aug 27 13:01:54 volumio volumio[975]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 27 13:01:54 volumio volumio[975]: info: Reading ALSA contributions from plugins.
Aug 27 13:01:54 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 43.
Aug 27 13:01:54 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:54 volumio systemd[1]: volumiobt.service: Consumed 1.034s CPU time.
Aug 27 13:01:54 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 27 13:01:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 27 13:01:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 13:01:54 volumio systemd[1]: shairport-sync.service: Consumed 2.116s CPU time.
Aug 27 13:01:54 volumio volumiobt[2977]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 13:01:54 volumio sudo[2973]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:54 volumio sudo[2979]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:54 volumio sudo[2979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:54 volumio sudo[2979]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:54 volumio volumio[975]: info: Shairport-Sync Started
Aug 27 13:01:54 volumio sudo[2981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:54 volumio sudo[2981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:54 volumio sudo[2981]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:54 volumio volumiobt[2983]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:54 volumio volumio[975]: info: Asound.conf file unchanged, so no further update is needed
Aug 27 13:01:54 volumio volumio[975]: info: Output device has changed, restarting MPD
Aug 27 13:01:54 volumio volumiobt[3000]: No default controller available
Aug 27 13:01:54 volumio sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 27 13:01:54 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:54 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:54 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync
Aug 27 13:01:54 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:54 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 13:01:54 volumio sudo[3005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 27 13:01:54 volumio sudo[3005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:54 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 27 13:01:54 volumio volumio[975]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 27 13:01:54 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:54 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 27 13:01:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 27 13:01:54 volumio systemd[1]: mpd.service: Consumed 1.223s CPU time.
Aug 27 13:01:54 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 27 13:01:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 27 13:01:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 27 13:01:54 volumio volumio[975]: info: QobuzConnect: setDeactiveState invoked
Aug 27 13:01:54 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:54 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 27 13:01:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 27 13:01:55 volumio sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 27 13:01:55 volumio sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:55 volumio sudo[3017]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 27 13:01:55 volumio sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 27 13:01:55 volumio sudo[3017]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:55 volumio sudo[3029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Aug 27 13:01:55 volumio sudo[3029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:55 volumio sudo[3021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 27 13:01:55 volumio sudo[3021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:55 volumio qobuz-connect[1941]: 20260827 13:01:55.468 [1941.1941] INFO SampleApp: shat down connection on UNIX socket
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 27 13:01:55 volumio sudo[3029]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:55 volumio volumio[975]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Aug 27 13:01:55 volumio systemd[1]: qobuz-connect.service: Deactivated successfully.
Aug 27 13:01:55 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Aug 27 13:01:55 volumio volumiobt[3035]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:55 volumio sudo[3033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Aug 27 13:01:55 volumio sudo[3033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:55 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 27 13:01:55 volumio sudo[2971]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:55 volumio volumiobt[3036]: [165B blob data]
Aug 27 13:01:55 volumio volumiobt[3036]: [bluetoothctl]> discoverable on
Aug 27 13:01:55 volumio volumiobt[3036]: No default controller available
Aug 27 13:01:55 volumio volumiobt[3036]: [bluetoothctl]> pairable on
Aug 27 13:01:55 volumio volumiobt[3036]: No default controller available
Aug 27 13:01:55 volumio volumio[975]: info: Received Get System Version
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 27 13:01:55 volumio volumio[975]: info: Received Get System Info
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 13:01:55 volumio volumiobt[3036]: [bluetoothctl]>
Aug 27 13:01:55 volumio volumiobt[3038]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:55 volumio sudo[3019]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:55 volumio volumio[975]: info: Discovery: Getting this device information
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:55 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 13:01:55 volumio sudo[3021]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:55 volumio volumiobt[3041]: No agent is registered
Aug 27 13:01:55 volumio volumiobt[3043]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:55 volumio volumiobt[3044]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:55 volumio volumio[975]: info: MPD Permissions set
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Aug 27 13:01:55 volumio volumio[975]: info: CoreStateMachine::getcurrentVolume
Aug 27 13:01:55 volumio volumio[975]: info: CoreCommandRouter::volumioRetrievevolume
Aug 27 13:01:55 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Aug 27 13:01:55 volumio systemd[1]: qobuz-connect.service: Deactivated successfully.
Aug 27 13:01:55 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 27 13:01:56 volumio volumio[975]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected
Aug 27 13:01:56 volumio volumio[975]: info: QobuzConnect: setDeactiveState invoked
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:56 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 27 13:01:56 volumio volumio[975]: info: Executing endpoint qc_getconfig
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Aug 27 13:01:56 volumio sudo[3033]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 13:01:56 volumio volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer sysdefault:5 load error: Protocol error
Aug 27 13:01:56 volumio volumiobt[3045]: 2026-08-27 13:01:56 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:56 volumio volumiobt[3045]: 2026-08-27 13:01:56 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:56 volumio volumiobt[3045]: 2026-08-27 13:01:56 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:56 volumio volumiobt[3045]: 2026-08-27 13:01:56 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:56 volumio volumiobt[3045]: Traceback (most recent call last):
Aug 27 13:01:56 volumio volumiobt[3045]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:56 volumio volumiobt[3045]: asyncio.run(_run())
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:56 volumio volumiobt[3045]: return runner.run(main)
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:56 volumio volumiobt[3045]: return self._loop.run_until_complete(task)
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:56 volumio volumiobt[3045]: return future.result()
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:56 volumio volumiobt[3045]: adapter_path = await find_adapter(bus)
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:56 volumio volumiobt[3045]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:56 volumio volumiobt[3045]: Exception: Bluetooth adapter not found
Aug 27 13:01:56 volumio volumiobt[3045]: Traceback (most recent call last):
Aug 27 13:01:56 volumio volumiobt[3045]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:56 volumio volumiobt[3045]: main()
Aug 27 13:01:56 volumio volumiobt[3045]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:56 volumio volumiobt[3045]: asyncio.run(_run())
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:56 volumio volumiobt[3045]: return runner.run(main)
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:56 volumio volumiobt[3045]: return self._loop.run_until_complete(task)
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:56 volumio volumiobt[3045]: return future.result()
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:56 volumio volumiobt[3045]: adapter_path = await find_adapter(bus)
Aug 27 13:01:56 volumio volumiobt[3045]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:56 volumio volumiobt[3045]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:56 volumio volumiobt[3045]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:56 volumio volumiobt[3045]: Exception: Bluetooth adapter not found
Aug 27 13:01:56 volumio volumio[975]: info: Executing endpoint qc_getconfig
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Aug 27 13:01:56 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:56 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:56 volumio systemd[1]: volumiobt.service: Consumed 1.061s CPU time.
Aug 27 13:01:56 volumio volumio[975]: info: TidalConnect service stoped!
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.914 [3047.3047] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.920 [3047.3047] INFO VolumeManager: [0x14c3068]: Setting new playback volume: 75
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.920 [3047.3047] INFO VolumeManager: [0x14c3068]: Setting new mute state: 0
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.920 [3047.3047] INFO AudioStreamManager: [0x14c2dc0]: Setting new audio download buffer size: 1048576
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.920 [3047.3047] INFO QobuzConnect: [0x14c3930]: Client initialized!
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.920 [3047.3047] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp
Aug 27 13:01:56 volumio volumio[975]: info: VolumeController:: Volume=undefined Mute =false
Aug 27 13:01:56 volumio volumio[975]: info: CoreStateMachine::pushState
Aug 27 13:01:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.959 [3047.3047] INFO LocalConfigManager: [0x14c2ae8]: Starting Local Configuration server
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.959 [3047.3047] INFO SampleApp: Starting Local configuration server
Aug 27 13:01:56 volumio qobuz-connect[3047]: 20260827 13:01:56.960 [3047.3047] INFO SampleApp: Connected to UNIX socket client 0x14ad818
Aug 27 13:01:57 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device
Aug 27 13:01:57 volumio volumio[975]: info: MRS: Pushing multiroomSync output
Aug 27 13:01:57 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 44.
Aug 27 13:01:57 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:57 volumio systemd[1]: volumiobt.service: Consumed 1.061s CPU time.
Aug 27 13:01:57 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:57 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock
Aug 27 13:01:57 volumio volumio[975]: info: CorePlayQueue::getTrackBlock
Aug 27 13:01:57 volumio volumio[975]: info: CoreCommandRouter::volumioRetrievevolume
Aug 27 13:01:57 volumio qobuz-connect[3047]: 20260827 13:01:57.063 [3047.3047] INFO SampleApp: Playback volume changed: 75
Aug 27 13:01:57 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:57 volumio volumiobt[3064]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:57 volumio sudo[3065]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:57 volumio sudo[3065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:57 volumio sudo[3065]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:57 volumio volumio[975]: info: TidalConnect service stoped!
Aug 27 13:01:57 volumio sudo[3067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:57 volumio sudo[3067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:57 volumio sudo[3067]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:57 volumio volumiobt[3069]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:57 volumio volumio[975]: info: Starting Shairport Sync
Aug 27 13:01:57 volumio volumio[975]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Aug 27 13:01:57 volumio volumio[975]: info: QobuzConnect: QOBUZ Connect daemon connected
Aug 27 13:01:57 volumio volumiobt[3077]: No default controller available
Aug 27 13:01:57 volumio sudo[3078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 27 13:01:57 volumio sudo[3078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:57 volumio volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer sysdefault:5 load error: Protocol error
Aug 27 13:01:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 27 13:01:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 27 13:01:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 13:01:57 volumio systemd[1]: shairport-sync.service: Consumed 1.292s CPU time.
Aug 27 13:01:57 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:57 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 27 13:01:57 volumio sudo[3078]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:57 volumio volumio[975]: info: VolumeController:: Volume=undefined Mute =false
Aug 27 13:01:57 volumio volumio[975]: info: CoreStateMachine::pushState
Aug 27 13:01:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState
Aug 27 13:01:57 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device
Aug 27 13:01:57 volumio volumio[975]: info: MRS: Pushing multiroomSync output
Aug 27 13:01:57 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:57 volumio volumio[975]: info: Shairport-Sync Started
Aug 27 13:01:57 volumio volumio[975]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Aug 27 13:01:57 volumio volumio[975]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Aug 27 13:01:58 volumio sudo[3102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Aug 27 13:01:58 volumio sudo[3102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:58 volumio volumiobt[3104]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:01:58 volumio volumiobt[3106]: [165B blob data]
Aug 27 13:01:58 volumio volumiobt[3106]: [bluetoothctl]> discoverable on
Aug 27 13:01:58 volumio volumiobt[3106]: No default controller available
Aug 27 13:01:58 volumio volumiobt[3106]: [bluetoothctl]> pairable on
Aug 27 13:01:58 volumio volumiobt[3106]: No default controller available
Aug 27 13:01:58 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Aug 27 13:01:58 volumio volumiobt[3106]: [bluetoothctl]>
Aug 27 13:01:58 volumio volumiobt[3107]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:01:58 volumio sudo[3102]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:58 volumio volumiobt[3111]: No agent is registered
Aug 27 13:01:58 volumio volumiobt[3112]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:01:58 volumio volumiobt[3113]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:01:58 volumio volumio[975]: info: Executing endpoint tc_getconfig
Aug 27 13:01:58 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Aug 27 13:01:59 volumio mpd[3030]: 2026-08-27T13:01:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 27 13:01:59 volumio volumio[975]: info: TidalConnect service stoped!
Aug 27 13:01:59 volumio vtcs[3108]: STARTING TidalConnect services, version: 1.6.1
Aug 27 13:01:59 volumio volumio[975]: info: TidalConnect service stoped!
Aug 27 13:01:59 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 27 13:01:59 volumio sudo[2931]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:59 volumio vtcs[3108]: STARTED TidalConnect services.
Aug 27 13:01:59 volumio sudo[3005]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:59 volumio volumio[975]: info: Executing endpoint tc_connect
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Aug 27 13:01:59 volumio volumio[975]: info: Connecting to TidalConnect
Aug 27 13:01:59 volumio volumio[975]: error: MPD error: The expression evaluated to a falsy value:
Aug 27 13:01:59 volumio volumio[975]: assert.ok(self.idling)
Aug 27 13:01:59 volumio volumio[975]: error: The expression evaluated to a falsy value:
Aug 27 13:01:59 volumio volumio[975]: assert.ok(self.idling)
Aug 27 13:01:59 volumio volumio[975]: error: updateQueue error: null
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState
Aug 27 13:01:59 volumio volumio[975]: info: CoreStateMachine::pushState
Aug 27 13:01:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState
Aug 27 13:01:59 volumio volumiobt[3114]: 2026-08-27 13:01:59 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:01:59 volumio volumiobt[3114]: 2026-08-27 13:01:59 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:01:59 volumio volumiobt[3114]: 2026-08-27 13:01:59 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:01:59 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device
Aug 27 13:01:59 volumio volumio[975]: info: MRS: Pushing multiroomSync output
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:59 volumio volumiobt[3114]: 2026-08-27 13:01:59 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:01:59 volumio volumiobt[3114]: Traceback (most recent call last):
Aug 27 13:01:59 volumio volumiobt[3114]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:59 volumio volumiobt[3114]: asyncio.run(_run())
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:59 volumio volumiobt[3114]: return runner.run(main)
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:59 volumio volumiobt[3114]: return self._loop.run_until_complete(task)
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:59 volumio volumiobt[3114]: return future.result()
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:59 volumio volumiobt[3114]: adapter_path = await find_adapter(bus)
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:59 volumio volumiobt[3114]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:59 volumio volumiobt[3114]: Exception: Bluetooth adapter not found
Aug 27 13:01:59 volumio volumiobt[3114]: Traceback (most recent call last):
Aug 27 13:01:59 volumio volumiobt[3114]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:01:59 volumio volumiobt[3114]: main()
Aug 27 13:01:59 volumio volumiobt[3114]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:01:59 volumio volumiobt[3114]: asyncio.run(_run())
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:01:59 volumio volumiobt[3114]: return runner.run(main)
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:01:59 volumio volumiobt[3114]: return self._loop.run_until_complete(task)
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:01:59 volumio volumiobt[3114]: return future.result()
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:01:59 volumio volumiobt[3114]: adapter_path = await find_adapter(bus)
Aug 27 13:01:59 volumio volumiobt[3114]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:01:59 volumio volumiobt[3114]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:01:59 volumio volumiobt[3114]: raise Exception("Bluetooth adapter not found")
Aug 27 13:01:59 volumio volumiobt[3114]: Exception: Bluetooth adapter not found
Aug 27 13:01:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:59 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState
Aug 27 13:01:59 volumio volumio[975]: info: CoreStateMachine::pushState
Aug 27 13:01:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState
Aug 27 13:01:59 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device
Aug 27 13:01:59 volumio volumio[975]: info: MRS: Pushing multiroomSync output
Aug 27 13:01:59 volumio volumio[975]: info: CoreCommandRouter::volumioGetState
Aug 27 13:01:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0
Aug 27 13:01:59 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect
Aug 27 13:01:59 volumio volumio[975]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Aug 27 13:01:59 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:01:59 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:01:59 volumio systemd[1]: volumiobt.service: Consumed 1.013s CPU time.
Aug 27 13:01:59 volumio volumio[975]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Aug 27 13:01:59 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 27 13:01:59 volumio volumio[975]: Error: ENOENT: no such file or directory, unlink '/tmp/uEBbCsflawcPaxQt6yMe2d9hdPEiV4ZFRWwjGjFHf19BcgjxC8uqPZbgQZnIHQPvL9Dfplp5WrZGzCfT1Mpdcsq8DU9qvHYspG04'
Aug 27 13:01:59 volumio volumio[975]: at Object.unlinkSync (node:fs:1899:3)
Aug 27 13:01:59 volumio volumio[975]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11265)
Aug 27 13:01:59 volumio volumio[975]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774)
Aug 27 13:01:59 volumio volumio[975]: at TidalConnectClient.emit (node:events:514:28)
Aug 27 13:01:59 volumio volumio[975]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44713)
Aug 27 13:01:59 volumio volumio[975]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41683
Aug 27 13:01:59 volumio volumio[975]: at Array.forEach ()
Aug 27 13:01:59 volumio volumio[975]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41561)
Aug 27 13:01:59 volumio volumio[975]: at Socket.emit (node:events:514:28)
Aug 27 13:01:59 volumio volumio[975]: at addChunk (node:internal/streams/readable:343:12)
Aug 27 13:01:59 volumio volumio[975]: at readableAddChunk (node:internal/streams/readable:316:9)
Aug 27 13:01:59 volumio volumio[975]: at Readable.push (node:internal/streams/readable:253:10)
Aug 27 13:01:59 volumio volumio[975]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) {
Aug 27 13:01:59 volumio volumio[975]: errno: -2,
Aug 27 13:01:59 volumio volumio[975]: syscall: 'unlink',
Aug 27 13:01:59 volumio volumio[975]: code: 'ENOENT',
Aug 27 13:01:59 volumio volumio[975]: path: '/tmp/uEBbCsflawcPaxQt6yMe2d9hdPEiV4ZFRWwjGjFHf19BcgjxC8uqPZbgQZnIHQPvL9Dfplp5WrZGzCfT1Mpdcsq8DU9qvHYspG04'
Aug 27 13:01:59 volumio volumio[975]: }
Aug 27 13:01:59 volumio volumio[975]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 27 13:01:59 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 45.
Aug 27 13:01:59 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:59 volumio systemd[1]: volumiobt.service: Consumed 1.013s CPU time.
Aug 27 13:01:59 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:01:59 volumio volumiobt[3151]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:01:59 volumio sudo[3152]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:01:59 volumio sudo[3152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:59 volumio sudo[3152]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:59 volumio sudo[3154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:01:59 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:01:59 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root
Aug 27 13:01:59 volumio volumiobt[3156]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:01:59 volumio volumiobt[3159]: No default controller available
Aug 27 13:02:01 volumio volumiobt[3162]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:02:01 volumio volumiobt[3163]: [165B blob data]
Aug 27 13:02:01 volumio volumiobt[3163]: [bluetoothctl]> discoverable on
Aug 27 13:02:01 volumio volumiobt[3163]: No default controller available
Aug 27 13:02:01 volumio volumiobt[3163]: [bluetoothctl]> pairable on
Aug 27 13:02:01 volumio volumiobt[3163]: No default controller available
Aug 27 13:02:01 volumio volumiobt[3163]: [bluetoothctl]>
Aug 27 13:02:01 volumio volumiobt[3164]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:02:01 volumio volumiobt[3166]: No agent is registered
Aug 27 13:02:01 volumio volumiobt[3167]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:02:01 volumio volumiobt[3168]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:02:01 volumio volumiobt[3169]: 2026-08-27 13:02:01 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:02:01 volumio volumiobt[3169]: 2026-08-27 13:02:01 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:02:01 volumio volumiobt[3169]: 2026-08-27 13:02:01 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:02:01 volumio volumiobt[3169]: 2026-08-27 13:02:01 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:02:01 volumio volumiobt[3169]: Traceback (most recent call last):
Aug 27 13:02:01 volumio volumiobt[3169]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:02:01 volumio volumiobt[3169]: asyncio.run(_run())
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:02:01 volumio volumiobt[3169]: return runner.run(main)
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:02:01 volumio volumiobt[3169]: return self._loop.run_until_complete(task)
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:02:01 volumio volumiobt[3169]: return future.result()
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:02:01 volumio volumiobt[3169]: adapter_path = await find_adapter(bus)
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:02:01 volumio volumiobt[3169]: raise Exception("Bluetooth adapter not found")
Aug 27 13:02:01 volumio volumiobt[3169]: Exception: Bluetooth adapter not found
Aug 27 13:02:01 volumio volumiobt[3169]: Traceback (most recent call last):
Aug 27 13:02:01 volumio volumiobt[3169]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:02:01 volumio volumiobt[3169]: main()
Aug 27 13:02:01 volumio volumiobt[3169]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:02:01 volumio volumiobt[3169]: asyncio.run(_run())
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:02:01 volumio volumiobt[3169]: return runner.run(main)
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:02:01 volumio volumiobt[3169]: return self._loop.run_until_complete(task)
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:02:01 volumio volumiobt[3169]: return future.result()
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:02:01 volumio volumiobt[3169]: adapter_path = await find_adapter(bus)
Aug 27 13:02:01 volumio volumiobt[3169]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:01 volumio volumiobt[3169]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:02:01 volumio volumiobt[3169]: raise Exception("Bluetooth adapter not found")
Aug 27 13:02:01 volumio volumiobt[3169]: Exception: Bluetooth adapter not found
Aug 27 13:02:01 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:02:01 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:02:02 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 46.
Aug 27 13:02:02 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:02:02 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:02:02 volumio volumiobt[3185]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 27 13:02:02 volumio sudo[3186]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 27 13:02:02 volumio sudo[3186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:02:02 volumio sudo[3186]: pam_unix(sudo:session): session closed for user root
Aug 27 13:02:02 volumio sudo[3188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 27 13:02:02 volumio sudo[3188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:02:02 volumio sudo[3188]: pam_unix(sudo:session): session closed for user root
Aug 27 13:02:02 volumio volumiobt[3190]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 27 13:02:02 volumio volumiobt[3193]: No default controller available
Aug 27 13:02:03 volumio sudo[3197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 13:01'
Aug 27 13:02:03 volumio sudo[3197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 13:02:03 volumio volumiobt[3199]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 27 13:02:03 volumio volumiobt[3200]: [83B blob data]
Aug 27 13:02:03 volumio volumiobt[3200]: No default controller available
Aug 27 13:02:03 volumio volumiobt[3200]: [bluetoothctl]> pairable on
Aug 27 13:02:03 volumio volumiobt[3200]: No default controller available
Aug 27 13:02:03 volumio volumiobt[3200]: [bluetoothctl]>
Aug 27 13:02:03 volumio volumiobt[3201]: INFO [BTSTART] Registering Bluetooth agent...
Aug 27 13:02:03 volumio volumiobt[3203]: No agent is registered
Aug 27 13:02:03 volumio volumiobt[3204]: INFO [BTSTART] Agent registered successfully.
Aug 27 13:02:03 volumio volumiobt[3205]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 27 13:02:04 volumio volumiobt[3206]: 2026-08-27 13:02:04 a2dp-agent [INFO] Connecting to system D-Bus
Aug 27 13:02:04 volumio volumiobt[3206]: 2026-08-27 13:02:04 a2dp-agent [INFO] Connected to system D-Bus
Aug 27 13:02:04 volumio volumiobt[3206]: 2026-08-27 13:02:04 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 27 13:02:04 volumio volumiobt[3206]: 2026-08-27 13:02:04 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 27 13:02:04 volumio volumiobt[3206]: Traceback (most recent call last):
Aug 27 13:02:04 volumio volumiobt[3206]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:02:04 volumio volumiobt[3206]: asyncio.run(_run())
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:02:04 volumio volumiobt[3206]: return runner.run(main)
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:02:04 volumio volumiobt[3206]: return self._loop.run_until_complete(task)
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:02:04 volumio volumiobt[3206]: return future.result()
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:02:04 volumio volumiobt[3206]: adapter_path = await find_adapter(bus)
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:02:04 volumio volumiobt[3206]: raise Exception("Bluetooth adapter not found")
Aug 27 13:02:04 volumio volumiobt[3206]: Exception: Bluetooth adapter not found
Aug 27 13:02:04 volumio volumiobt[3206]: Traceback (most recent call last):
Aug 27 13:02:04 volumio volumiobt[3206]: File "/bin/bt/a2dp-agent", line 234, in
Aug 27 13:02:04 volumio volumiobt[3206]: main()
Aug 27 13:02:04 volumio volumiobt[3206]: File "/bin/bt/a2dp-agent", line 225, in main
Aug 27 13:02:04 volumio volumiobt[3206]: asyncio.run(_run())
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 27 13:02:04 volumio volumiobt[3206]: return runner.run(main)
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 27 13:02:04 volumio volumiobt[3206]: return self._loop.run_until_complete(task)
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 27 13:02:04 volumio volumiobt[3206]: return future.result()
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/bin/bt/a2dp-agent", line 175, in _run
Aug 27 13:02:04 volumio volumiobt[3206]: adapter_path = await find_adapter(bus)
Aug 27 13:02:04 volumio volumiobt[3206]: ^^^^^^^^^^^^^^^^^^^^^^^
Aug 27 13:02:04 volumio volumiobt[3206]: File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 27 13:02:04 volumio volumiobt[3206]: raise Exception("Bluetooth adapter not found")
Aug 27 13:02:04 volumio volumiobt[3206]: Exception: Bluetooth adapter not found
Aug 27 13:02:04 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 13:02:04 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 27 13:02:04 volumio systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 47.
Aug 27 13:02:04 volumio systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:02:04 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 27 13:02:04 volumio volumiobt[3209]: INFO [BTSTART] Ensuring Bluetooth directory exists...
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"