I have a Sonoff sigbee usb dongle connected to my rpi and have a couple of devices on it now. In the first week of March I bought an Ikea VINDSTYRKA humidity/temperature/pm2.5 sensor. I paired it and it has been working ever since. The connected symbol is shown in the top right of the device and the values are updated regulary in HA.
I bought a second one this weekend. Pairing works just as the one before, but after pairing it does not stay connected. The connected symbol blinks a few seconds after pairing completes, but then it turns off again and the values are not shown at all in HA.
I have been digging in the debuglog I saved and found this:
2024-04-03 22:12:51.590 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] Discovering entities for endpoint: xx:xx:xx:xx:xx:xx:xx:xx-1
2024-04-03 22:12:51.592 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'button' platform -> 'ZHAIdentifyButton' using ['identify']
2024-04-03 22:12:51.592 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' platform -> 'Temperature' using ['temperature']
2024-04-03 22:12:51.592 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' platform -> 'PM25' using ['pm25']
2024-04-03 22:12:51.592 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' platform -> 'RSSISensor' using ['basic']
2024-04-03 22:12:51.592 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' platform -> 'LQISensor' using ['basic']
2024-04-03 22:12:51.592 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' platform -> 'Humidity' using ['humidity']
2024-04-03 22:12:51.594 DEBUG (Thread-6) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f75c431f0>, '\n INSERT INTO attributes_cache_v12\n VALUES (:ieee, :endpoint_id, :cluster_id, :attrid, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': xx:xx:xx:xx:xx:xx:xx:xx, 'endpoint_id': 1, 'cluster_id': 0, 'attrid': 4, 'value': 'IKEA of Sweden', 'timestamp': 1712175171.585985, 'min_update_delta': 30.0})
2024-04-03 22:12:51.594 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'update' platform -> 'ZHAFirmwareUpdateEntity' using ['ota']
2024-04-03 22:12:51.595 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] Discovering entities for endpoint: xx:xx:xx:xx:xx:xx:xx:xx-242
2024-04-03 22:12:51.597 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x7BC5](VINDSTYRKA): starting availability checks - interval: 81
2024-04-03 22:12:51.597 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] Discovering entities for device: xx:xx:xx:xx:xx:xx:xx:xx-IKEA of Sweden VINDSTYRKA
2024-04-03 22:12:51.597 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] Attempting to discover quirks v2 entities for device: xx:xx:xx:xx:xx:xx:xx:xx-IKEA of Sweden VINDSTYRKA
2024-04-03 22:12:51.597 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] Device: xx:xx:xx:xx:xx:xx:xx:xx-IKEA of Sweden VINDSTYRKA is not a quirks v2 device - skipping discover_quirks_v2_entities
2024-04-03 22:12:51.597 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x7BC5:xx:xx:xx:xx:xx:xx:xx:xx entering async_device_initialized - is_new_join: True
2024-04-03 22:12:51.598 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x7BC5:xx:xx:xx:xx:xx:xx:xx:xx has joined the ZHA zigbee network
2024-04-03 22:12:51.598 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x7BC5](VINDSTYRKA): started configuration
2024-04-03 22:12:51.598 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:ZDO](VINDSTYRKA): 'async_configure' stage succeeded
2024-04-03 22:12:51.600 DEBUG (Thread-6) [aiosqlite] returning exception FOREIGN KEY constraint failed
2024-04-03 22:12:51.602 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x042a]: Performing cluster binding
2024-04-03 22:12:51.603 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2024, 4, 3, 20, 12, 51, 603088, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x7BC5), dst_ep=0, source_route=None, extended_timeout=False, tsn=6, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x06\x16\x99[\xfe\xff\xd4m\xa4\x01*\x04\x03\xc4\x9d:,\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2024-04-03 22:12:51.603 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x7BC5, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.MTO_ROUTE|NO_ROUTE_CACHE: 3>)
2024-04-03 22:12:51.603 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0003]: Configuring cluster attribute reporting
2024-04-03 22:12:51.604 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0003]: finished cluster handler configuration
2024-04-03 22:12:51.604 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0000]: Configuring cluster attribute reporting
2024-04-03 22:12:51.604 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0000]: finished cluster handler configuration
2024-04-03 22:12:51.604 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0405]: Performing cluster binding
2024-04-03 22:12:51.604 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2024, 4, 3, 20, 12, 51, 604913, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x7BC5), dst_ep=0, source_route=None, extended_timeout=False, tsn=7, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x07\x16\x99[\xfe\xff\xd4m\xa4\x01\x05\x04\x03\xc4\x9d:,\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2024-04-03 22:12:51.605 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0402]: Performing cluster binding
2024-04-03 22:12:51.605 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2024, 4, 3, 20, 12, 51, 605657, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x7BC5), dst_ep=0, source_route=None, extended_timeout=False, tsn=8, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x08\x16\x99[\xfe\xff\xd4m\xa4\x01\x02\x04\x03\xc4\x9d:,\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2024-04-03 22:12:51.605 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0019]: finished cluster handler configuration
2024-04-03 22:12:51.606 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x7BC5:1:0x0019]: finished cluster handler configuration
2024-04-03 22:12:51.608 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (xx:xx:xx:xx:xx:xx:xx:xx, 1, 0, 4, 'IKEA of Sweden', datetime.datetime(2024, 4, 3, 20, 12, 51, 585985, tzinfo=datetime.timezone.utc)) params: FOREIGN KEY constraint failed
2024-04-03 22:12:51.608 DEBUG (Thread-6) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f75c431f0>, '\n INSERT INTO attributes_cache_v12\n VALUES (:ieee, :endpoint_id, :cluster_id, :attrid, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': xx:xx:xx:xx:xx:xx:xx:xx, 'endpoint_id': 1, 'cluster_id': 0, 'attrid': 5, 'value': 'VINDSTYRKA', 'timestamp': 1712175171.586034, 'min_update_delta': 30.0})
2024-04-03 22:12:51.609 DEBUG (Thread-6) [aiosqlite] returning exception FOREIGN KEY constraint failed
2024-04-03 22:12:51.610 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2024-04-03 22:12:51.612 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (xx:xx:xx:xx:xx:xx:xx:xx, 1, 0, 5, 'VINDSTYRKA', datetime.datetime(2024, 4, 3, 20, 12, 51, 586034, tzinfo=datetime.timezone.utc)) params: FOREIGN KEY constraint failed
2024-04-03 22:12:51.613 DEBUG (Thread-6) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f75c431f0>, 'INSERT INTO devices_v12 (ieee, nwk, status, last_seen)\n VALUES (?, ?, ?, ?)\n ON CONFLICT (ieee)\n DO UPDATE SET\n nwk=excluded.nwk,\n status=excluded.status,\n last_seen=excluded.last_seen', (xx:xx:xx:xx:xx:xx:xx:xx, 0x7BC5, <Status.ENDPOINTS_INIT: 2>, 1712175171.582685))
2024-04-03 22:12:51.614 DEBUG (Thread-6) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f75c431f0>, 'INSERT INTO devices_v12 (ieee, nwk, status, last_seen)\n VALUES (?, ?, ?, ?)\n ON CONFLICT (ieee)\n DO UPDATE SET\n nwk=excluded.nwk,\n status=excluded.status,\n last_seen=excluded.last_seen', (xx:xx:xx:xx:xx:xx:xx:xx, 0x7BC5, <Status.ENDPOINTS_INIT: 2>, 1712175171.582685)) completed
2024-04-03 22:12:51.615 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x7BC5, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.MTO_ROUTE|NO_ROUTE_CACHE: 3>)
2024-04-03 22:12:51.619 DEBUG (Thread-6) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f75c431f0>, 'INSERT INTO node_descriptors_v12\n VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)\n ON CONFLICT (ieee)\n DO UPDATE SET\n logical_type=excluded.logical_type,\n complex_descriptor_available=excluded.complex_descriptor_available,\n user_descriptor_available=excluded.user_descriptor_available,\n reserved=excluded.reserved,\n aps_flags=excluded.aps_flags,\n frequency_band=excluded.frequency_band,\n mac_capability_flags=excluded.mac_capability_flags,\n manufacturer_code=excluded.manufacturer_code,\n maximum_buffer_size=excluded.maximum_buffer_size,\n maximum_incoming_transfer_size=excluded.maximum_incoming_transfer_size,\n server_mask=excluded.server_mask,\n maximum_outgoing_transfer_size=excluded.maximum_outgoing_transfer_size,\n descriptor_capability_field=excluded.descriptor_capability_field', (xx:xx:xx:xx:xx:xx:xx:xx, <LogicalType.Router: 1>, 0, 0, 0, 0, <FrequencyBand.Freq2400MHz: 8>, <MACCapabilityFlags.FullFunctionDevice|MainsPowered|RxOnWhenIdle|AllocateAddress: 142>, 4476, 82, 82, 11264, 82, <DescriptorCapability.NONE: 0>))
2024-04-03 22:14:03.387 ERROR (MainThread) [zigpy.zcl] [0xEE17:1:0x0405] Traceback (most recent call last):
File "/usr/local/lib/python3.12/site-packages/zigpy/zcl/__init__.py", line 415, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.12/site-packages/zigpy/endpoint.py", line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.12/site-packages/zigpy/device.py", line 508, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.12/site-packages/zigpy/device.py", line 334, in request
await send_request()
File "/usr/local/lib/python3.12/site-packages/zigpy/application.py", line 841, in request
await self.send_packet(
File "/usr/local/lib/python3.12/site-packages/zigpy_znp/zigbee/application.py", line 959, in send_packet
await self._send_request_raw(
File "/usr/local/lib/python3.12/site-packages/zigpy_znp/zigbee/application.py", line 850, in _send_request_raw
response = await asyncio.shield(
^^^^^^^^^^^^^^^^^^^^^
asyncio.exceptions.CancelledError
Is this a known issue and can I somehow correct this? This happens with 2024.3.3 and 2024.4.0