search

sopelj / hass-ember-mug-component

Ember Mug Integration for Home Assistant
MIT License
113 stars 4 forks source link

Ember integration not working with HA core 2022.9.1 #10

Closed rybackisback closed 1 year ago

rybackisback commented 2 years ago

Hi, @sopelj, now the ember mug wont connect at all after updated HA core to 2022.9

sopelj commented 2 years ago

Hmm, that's weird. Do you have any errors? Are you sure home assistant's bluetooth integration is disabled and no other integrations are using bluetooth? Have you tried powering bluetooth off and on again?

rybackisback commented 2 years ago

all the sensors became unavailable after the update so i deleted and re-installed the integration. After that i am unable to pair the ember mug. It just wont detect the mug after putting it into pairing mode. The bluetooth integration in HA is disabled and no other integration is using bluetooth. Ive tried turning off and on the bluetooth in ubuntu and i can pair to the mug in ubunutu but not in HA and it doesnt stay connected even in ubuntu.

brichad commented 2 years ago

I have this issue too, now can't pair the mug at all. Did you manage to resolve? i can help debug if needed.

sopelj commented 2 years ago

Oh ok thank you very much. So have you tried resetting the mug as well? Make sure no other device is connected/paired with it, like your computer. It only seems to work with one at a time and needs to be reset. You have absolutely no errors in Hass? I've been trying to update it to the new Bluetooth integration so I don't have the old version installed, I'll have a look after work tonight.

rybackisback commented 2 years ago

Yes ive reset the mug and tried again. The integration does not find the mug. I cannot see any errors in hass.

brichad commented 2 years ago

i've reset the mug multiple times, i manually purged bluetooth configs and the bluetooth from the hass host, ran monitor along side and it identified the mug... either way i get a timeout on connection attempt, if i try run this multiple times, it connects but then basically says it's not available and the mug remains in paring mode.

On Fri, Sep 16, 2022 at 11:55 PM rybackisback @.***> wrote:

Yes ive reset the mug and tried again. The integration does not find the mug. I cannot see any errors in hass.

— Reply to this email directly, view it on GitHub https://github.com/sopelj/hass-ember-mug-component/issues/10#issuecomment-1249922116, or unsubscribe https://github.com/notifications/unsubscribe-auth/AK3AYKKUBJ7MW5N3OQYORSDV6T3G7ANCNFSM6AAAAAAQJSLZME . You are receiving this because you commented.Message ID: @.***>

sopelj commented 2 years ago

It times out trying to pair in bluetoothctl too? If you can't pair/trust in manually, then it definitely won't work in the integration, because it uses the same thing behind the scenes. It might be the adapter the problem too if it won't work at all. Mine stopped working after the update too, so I switched to one of the supported ones listed on their wiki, and it seems to work again. Maybe they did something that prevents others from working. They added a ton of stuff for bluetooth proxies. If you don't see any errors for my integration, you can increase the logging to debug:

logger:
  default: error  # Or whatever you want
  logs:
    custom_components.ember_mug: debug
    custom_components.ember_mug.mug: debug
    custom_components.ember_mug.mug.api: debug
brichad commented 2 years ago

i can pair it manually, just not in hass... i'll increase the loggin shortly and confirm back :)

sopelj commented 2 years ago

@brichad Hey, did you have any luck? If not, I'm testing a new version that works on 2022.9+ and uses the built-in bluetooth integration. You can try the beta version of 0.4.0 if you'd like. Just make sure to remove your previous device and unpair it in with bluetoothctl remove youmacaddress. Then put it in pairing mode and it should be auto discovered and offer to setup.

rybackisback commented 2 years ago

@sopelj

I still am not able to setup the mug with the built-in HA bluetooth integration and the beta version of 0.4.0. I cannot even install the ember integration. I get the error below.

Error: Config flow could not be loaded: 500 Internal Server Error Server got itself in trouble

Logger: aiohttp.server Source: custom_components/ember_mug/config_flow.py:75 Integration: Ember Mug (documentation, issues) First occurred: 11:14:13 AM (1 occurrences) Last logged: 11:14:13 AM

Error handling request Traceback (most recent call last): File "/usr/local/lib/python3.10/site-packages/aiohttp/web_protocol.py", line 435, in _handle_request resp = await request_handler(request) File "/usr/local/lib/python3.10/site-packages/aiohttp/web_app.py", line 504, in _handle resp = await handler(request) File "/usr/local/lib/python3.10/site-packages/aiohttp/web_middlewares.py", line 117, in impl return await handler(request) File "/usr/src/homeassistant/homeassistant/components/http/security_filter.py", line 60, in security_filter_middleware return await handler(request) File "/usr/src/homeassistant/homeassistant/components/http/forwarded.py", line 100, in forwarded_middleware return await handler(request) File "/usr/src/homeassistant/homeassistant/components/http/request_context.py", line 28, in request_context_middleware return await handler(request) File "/usr/src/homeassistant/homeassistant/components/http/ban.py", line 82, in ban_middleware return await handler(request) File "/usr/src/homeassistant/homeassistant/components/http/auth.py", line 236, in auth_middleware return await handler(request) File "/usr/src/homeassistant/homeassistant/components/http/view.py", line 136, in handle result = await result File "/usr/src/homeassistant/homeassistant/components/config/config_entries.py", line 148, in post return await super().post(request) File "/usr/src/homeassistant/homeassistant/components/http/data_validator.py", line 73, in wrapper result = await method(view, request, data, *args, **kwargs) File "/usr/src/homeassistant/homeassistant/helpers/data_entry_flow.py", line 71, in post result = await self._flow_mgr.async_init( File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 225, in async_init flow, result = await task File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 252, in _async_init result = await self._async_handle_step(flow, flow.init_step, data, init_done) File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 367, in _async_handle_step result: FlowResult = await getattr(flow, method)(user_input) File "/config/custom_components/ember_mug/config_flow.py", line 75, in async_step_user with BleakClient(discovery_info.device) as client: AttributeError: enter

sopelj commented 2 years ago

@rybackisback Oh, I'm sorry. That does happen if you try to add it manually. I'll fix that. However, you shouldn't need to. Just install/update the mug integration and make sure you have the Bluetooth adaptor configured with the Bluetooth integration and when you put the mug in pairing mode it should be automatically detected as a new device. You no longer have to do "add integration" simply having it installed will suffice.

rybackisback commented 2 years ago

Thanks @sopelj. i followed your instructions and put the mug into pairing mode. Now I get the following error.

This error originated from a custom integration.

Logger: custom_components.ember_mug.coordinator Source: custom_components/ember_mug/coordinator.py:88 Integration: Ember Mug (documentation, issues) First occurred: 4:41:23 PM (3 occurrences) Last logged: 4:41:40 PM

Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4

sopelj commented 2 years ago

@rybackisback Oh that's good it got paired though. Would it be possible to enable more detailed debugging so I can see which attribute is problematic? Thanks!

rybackisback commented 2 years ago

Full logs 2022-10-11 06:00:26.367 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration ember_mug which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2022-10-11 06:00:49.254 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 06:00:49.254 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 06:01:47.467 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-11 06:01:47.471 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.216 seconds (success: False) 2022-10-11 06:03:35.801 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 06:03:35.801 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 06:04:33.858 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-11 06:04:33.858 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.057 seconds (success: False) 2022-10-11 06:04:43.860 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 06:04:43.860 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 06:05:41.960 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-11 06:05:41.961 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.101 seconds (success: False) 2022-10-11 06:06:02.723 DEBUG (MainThread) [custom_components.ember_mug] Discovered bluetooth device: BluetoothServiceInfoBleak(name='Ember Ceramic Mug', address='XXXXXXXXXXXXX', rssi=-90, manufacturer_data={961: b'\x81'}, service_data={}, service_uuids=[XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX'], source='XXXXXXXXXXXXX', device=XXXXXXXXXXXXX: Ember Ceramic Mug, advertisement=AdvertisementData(local_name='Ember Ceramic Mug', manufacturer_data={961: b'\x81'}, service_uuids=[XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX']), connectable=True, time=344975.422696435) 2022-10-11 06:06:10.802 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 06:06:10.802 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 06:06:14.784 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-11 06:06:14.786 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX data in 3.984 seconds (success: False) 2022-10-11 06:06:19.787 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 06:06:19.787 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 06:06:23.470 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-11 06:06:23.471 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX data in 3.684 seconds (success: False) 2022-10-11 06:06:33.472 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 06:06:33.472 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating

sopelj commented 2 years ago

Oh, thank you. That doesn't give the location, sadly. I assume it's the UDSK, since that's the only place, a side from the ID, that uses that method. And I'm fairly confident that the ID is fine. I have caught that error in version 0.4.0-beta-4 if you want to try. If you still have issues with it please try enabling logging debugging from the module ember_mug as well. :)

rybackisback commented 2 years ago

See error below error with version 0.4.0-beta-4:

2022-10-11 20:58:04.647 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration ember_mug which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2022-10-11 20:58:30.296 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 20:58:30.296 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 20:59:28.468 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-11 20:59:28.470 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.173 seconds (success: False) 2022-10-11 21:01:36.885 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 21:01:36.885 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:02:34.987 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-11 21:02:34.988 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.103 seconds (success: False) 2022-10-11 21:02:44.990 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 21:02:44.990 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:03:43.013 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-11 21:03:43.014 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.024 seconds (success: False) 2022-10-11 21:11:34.055 DEBUG (MainThread) [custom_components.ember_mug] Discovered bluetooth device: BluetoothServiceInfoBleak(name='Ember Ceramic Mug', address='XXXXXXXXXXXXXXX', rssi=-85, manufacturer_data={961: b'\x81'}, service_data={}, service_uuids=[XXXXXXXXXXXXXXXXXXXXXX], source='XXXXXXXXXXXX', device=XXXXXXXXXXXXXXX: Ember Ceramic Mug, advertisement=AdvertisementData(local_name='Ember Ceramic Mug', manufacturer_data={961: b'\x81'}, service_uuids=[XXXXXXXXXXXXXXX]), connectable=True, time=1513.759913191) 2022-10-11 21:11:43.402 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-11 21:11:43.402 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:11:45.096 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-11 21:11:45.097 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.695 seconds (success: False) 2022-10-11 21:11:50.098 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-11 21:11:50.099 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:11:51.801 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-11 21:11:51.802 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.704 seconds (success: False) 2022-10-11 21:12:01.661 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-11 21:12:01.661 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:12:04.491 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-11 21:12:04.492 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 2.832 seconds (success: False) 2022-10-11 21:12:09.494 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-11 21:12:09.494 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:12:11.061 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-11 21:12:11.062 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.568 seconds (success: False) 2022-10-11 21:12:20.036 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-11 21:12:20.036 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:12:43.026 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-11 21:12:43.026 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:12:44.552 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: [org.bluez.Error.Failed] Operation already in progress 2022-10-11 21:12:44.553 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 24.517 seconds (success: False) 2022-10-11 21:12:49.555 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-11 21:12:49.555 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-11 21:12:50.070 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: [org.bluez.Error.Failed] Operation already in progress 2022-10-11 21:12:50.071 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 7.045 seconds (success: False)

sopelj commented 2 years ago

That's very weird. It seems like the same error as beta-3... that shouldn't be possible. No warnings from the library either. Are you sure you restarted after and have detailed logging enabled for the ember mug library?

logger:
  default: error  # Or whatever you want
  logs:
    custom_components.ember_mug: debug
    ember_mug: debug
    ember_mug.connection: debug
    ember_mug.mug
rybackisback commented 2 years ago

Yes I did restart and install beta 4. Ive changed the logger as above and see errors below: Full logs 2022-10-12 06:36:50.883 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration ember_mug which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2022-10-12 06:37:09.104 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:37:09.104 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:38:07.497 ERROR (MainThread) [custom_components.ember_mug.coordinator] (xxxxxxxxxxxxxxxx) - /org/bluez/hci0/dev_xxxxxxxxxxxxxxxxxx: Failed to connect: 2022-10-12 06:38:07.498 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 58.394 seconds (success: False) 2022-10-12 06:39:21.932 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:39:21.932 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:40:20.058 ERROR (MainThread) [custom_components.ember_mug.coordinator] (xxxxxxxxxxxxxxxx) - /org/bluez/hci0/dev_xxxxxxxxxxxxxxxxxx: Failed to connect: 2022-10-12 06:40:20.059 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 58.127 seconds (success: False) 2022-10-12 06:40:30.061 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:40:30.061 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:41:22.149 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 06:41:22.150 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 52.089 seconds (success: False) 2022-10-12 06:41:37.832 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:41:37.832 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:41:39.022 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-12 06:41:39.027 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 1.195 seconds (success: False) 2022-10-12 06:41:42.151 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:41:42.152 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:41:44.029 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:41:44.029 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:41:44.917 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-12 06:41:44.917 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 2.766 seconds (success: False) 2022-10-12 06:41:44.917 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-12 06:41:44.918 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 0.888 seconds (success: False) 2022-10-12 06:41:50.395 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:41:50.395 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:41:51.579 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 06:41:51.581 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 1.186 seconds (success: False) 2022-10-12 06:41:56.582 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:41:56.582 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:42:00.443 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-12 06:42:00.445 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 3.863 seconds (success: False) 2022-10-12 06:42:10.447 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:42:10.447 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:42:24.918 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:42:24.918 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:42:26.227 ERROR (MainThread) [custom_components.ember_mug.coordinator] (xxxxxxxxxxxxxxxx) - /org/bluez/hci0/dev_xxxxxxxxxxxxxxxxxx: Failed to connect: [org.bluez.Error.Failed] Operation already in progress 2022-10-12 06:42:26.228 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 15.780 seconds (success: False) 2022-10-12 06:42:46.228 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:42:46.228 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:42:46.738 ERROR (MainThread) [custom_components.ember_mug.coordinator] (xxxxxxxxxxxxxxxx) - /org/bluez/hci0/dev_xxxxxxxxxxxxxxxxxx: Failed to connect: [org.bluez.Error.Failed] Operation already in progress 2022-10-12 06:42:46.739 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 21.821 seconds (success: False) 2022-10-12 06:43:20.726 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 06:43:20.727 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 34.499 seconds (success: False) 2022-10-12 06:44:00.729 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:44:00.730 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:44:06.741 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:44:06.741 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:44:08.532 ERROR (MainThread) [custom_components.ember_mug.coordinator] (xxxxxxxxxxxxxxxx) - /org/bluez/hci0/dev_xxxxxxxxxxxxxxxxxx: Failed to connect: [org.bluez.Error.Failed] Operation already in progress 2022-10-12 06:44:08.533 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 1.792 seconds (success: False) 2022-10-12 06:44:20.306 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-12 06:44:20.306 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 19.577 seconds (success: False) 2022-10-12 06:44:50.600 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:44:50.600 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:44:54.596 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 06:44:54.596 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 3.996 seconds (success: False) 2022-10-12 06:44:59.603 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:44:59.603 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:45:02.157 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 06:45:02.158 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 2.555 seconds (success: False) 2022-10-12 06:45:12.160 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:45:12.160 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 06:45:15.973 ERROR (MainThread) [custom_components.ember_mug.coordinator] Invalid base64-encoded string: number of data characters (1) cannot be 1 more than a multiple of 4 2022-10-12 06:45:15.974 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx data in 3.814 seconds (success: False) 2022-10-12 06:45:28.534 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx Setup 2022-10-12 06:45:28.534 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating

brichad commented 2 years ago

Hi!! sorry back from travels, i'm getting the same errors as @rybackisback (above), identification of the mug working good though :)

sopelj commented 2 years ago

Welcome back!

Ok, sorry for the delay, I'm really busy this week. I think I fixed the encoding issue on 0.4.0-beta5. it definitely should cause that error at least. If you guys do still have issues, please try with warnings enabled from the library ember_mug as mentioned above though as it will help pinpoint the problem as it doesn't occur with my mug. Probably since the values are different. Thanks!

rybackisback commented 2 years ago

See errors below with beta 5: Full logs 2022-10-12 14:57:08.004 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration ember_mug which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2022-10-12 14:57:32.999 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-12 14:57:33.000 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 14:58:31.072 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-12 14:58:31.075 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.075 seconds (success: False) 2022-10-12 14:59:44.552 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-12 14:59:44.552 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:00:42.624 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-12 15:00:42.624 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.072 seconds (success: False) 2022-10-12 15:00:52.625 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX Setup 2022-10-12 15:00:52.625 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:01:50.643 ERROR (MainThread) [custom_components.ember_mug.coordinator] (XXXXXXXXXXXXX) - /org/bluez/hci0/dev_XXXXXXXXXXXXXXX: Failed to connect: 2022-10-12 15:01:50.644 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXX data in 58.018 seconds (success: False) 2022-10-12 15:18:11.273 DEBUG (MainThread) [custom_components.ember_mug] Discovered bluetooth device: BluetoothServiceInfoBleak(name='Ember Ceramic Mug', address='XXXXXXXXXXXXX', rssi=-77, manufacturer_data={961: b'\x81'}, service_data={}, service_uuids=['XXXXXXXXXXXXXXXXXXXXXXXXXXXXX', 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX', 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX', 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX'], source='A4:6B:B6:2A:25:A5', device=XXXXXXXXXXXXX: Ember Ceramic Mug, advertisement=AdvertisementData(local_name='Ember Ceramic Mug', manufacturer_data={961: b'\x81'}, service_uuids=['XXXXXXXXXXXXXXXXXXXXXXXXXXXXX', 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX', 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX', 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX']), connectable=True, time=66710.978258687) 2022-10-12 15:18:18.564 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-12 15:18:18.564 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:18:19.764 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 15:18:19.769 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.204 seconds (success: False) 2022-10-12 15:18:24.770 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-12 15:18:24.771 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:18:26.064 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 15:18:26.065 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.295 seconds (success: False) 2022-10-12 15:18:36.066 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-12 15:18:36.066 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:18:37.184 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 15:18:37.185 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.119 seconds (success: False) 2022-10-12 15:18:57.186 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-12 15:18:57.186 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:18:58.376 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 15:18:58.377 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.191 seconds (success: False) 2022-10-12 15:19:38.379 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-12 15:19:38.379 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:19:39.686 ERROR (MainThread) [custom_components.ember_mug.coordinator] 2022-10-12 15:19:39.687 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.308 seconds (success: False) 2022-10-12 15:20:59.688 INFO (MainThread) [custom_components.ember_mug.coordinator] Ember Mug ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXSetup 2022-10-12 15:20:59.688 DEBUG (MainThread) [custom_components.ember_mug.coordinator] Updating 2022-10-12 15:21:01.003 ERROR (MainThread) [custom_components.ember_mug.coordinator] [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error) 2022-10-12 15:21:01.005 DEBUG (MainThread) [custom_components.ember_mug] Finished fetching ember-mug-XXXXXXXXXXXXXXXXXXXXXXXXXXXXdata in 1.316 seconds (success: False)

sopelj commented 2 years ago

Hmm, ok, thank you. The ATT error: 0x0e is really strange and I don't know the cause. It happens when it can't make the initial connection. Have you tried any of the following since updating to beta 5?

Thanks!

rybackisback commented 2 years ago

Hi @sopelj. thanks for the prompt reply. See answers below:

placing the mug in pairing mode and taking it out after a few minutes reloading the integration?: yes, I have tried this but the error remains restarting home assistant: yes, I have but the error remains updating to the latest home assistant and home assistant os: yes, i am on core-2022.10.3 and supervisor-2022.10.0 running on Ubuntu 22.04.1 LTS opening bluetoothctl to see the output: i am not sure how to do this, can you please provide some guidance? removing the mug, resetting it and forgetting it in bluetoothctl: i am not sure how to do this, can you please provide some guidance? Also, is your Bluetooth adapter one of the ones officially supported by home assistant? I am using the in built bluetooth module on my Intel NUC11PAHi7 which i have paired other devices to previously including the ember mug.

sopelj commented 2 years ago

Thank you very much for the detailed answer. And your tests. :) I think it is because the Bluetooth is stuck in passive scanning mode, and thus it won't connect. I've been trying to figure out how to wake it. Opening bluetoothctl manually seems to do it. Ok, so you're not running Home Assistant OS. Are you running in Docker or directly under Ubuntu? If you're running directly in Ubuntu I suppose it probably uses Ubuntu's bluetooth directly. So just open a terminal and type bluetoothctl and it should open a shell and list the scanned/connected devices. If you're running in docker, you will need to docker exec -it containername bluetoothctl I assume. Oh ok. It's just home assistant has a particular setup with their bluetooth which is fairly impressive, but does not play well with all adaptors. I had to buy a new one, but I don't know if mine just stopped working because of a fault or because it didn't work with the new platform.

rybackisback commented 2 years ago

Hi @sopelj , thanks for your help. Running Bluetoothctl worked. Hope you are able to find a way to wake it when the mug is put into pairing mode.

sopelj commented 2 years ago

Awesome! I'm glad that worked. At least that confirms the issue and workaround. I'll see what can be done. It's only required for the in initial setup though. After that you should be good even after reboots.

rybackisback commented 2 years ago

Thanks @sopelj, once the connection is established the connection is solid with frequent updates

sopelj commented 2 years ago

Home Assistant 2022.11 Beta 0 was just released and it includes a more recent version of the Bluetooth libraries that this integration uses. Since the update I haven't seen the "ATT error: 0x0e (Unlikely Error)". I don't know if anyone else has tried. But it's encouraging.

rwatts3 commented 1 year ago

If you run bluetoothctl power on this will turn on the bluetooth dongle.

See this article. After this I was able to pair successfully. https://www.homeautomationguy.io/home-assistant-tips/failed-to-start-bluetooth-org-bluez-error-notready-resource-not-ready/

sopelj commented 1 year ago

@rwatts3 Oh great, I'm glad that worked for you. In most cases if Bluetooth is already configured in Home Assistant then you shouldn't need to power it on, as it should be on already. But perhaps in certain setups this can be necessary. I can add it to the troubleshooting section just in case though.

sopelj commented 1 year ago

This issue covers multiple problems, and the title does not represent most of them and leads to confusion. Normally they are all fixed or documented. I'm going to close it, but Feel free to create a new issue if something was missed.