core: ZHA will not set up - timeout error

The problem

Zigbee network will not load and all devices are offline. Integration fails to set up. Logs indicate that a timeout error occurred. I am running a Sonoff Zigbee Bridge running tasmota 13.2.0.1 using ZHA via a socket connection. Console shows a connection is made, however it does not look like it fully connects to the system.

Thank you. if you need any further information let me know.

Link to relevant Home Assistant Community Thread: here

What version of Home Assistant Core has the issue?

core-2023.10.4

What was the last working version of Home Assistant Core?

core-2023.10.3

What type of installation are you running?

Home Assistant OS

Integration causing the issue

ZHA

Link to integration documentation on our website

No response

Diagnostics information

No response

Example YAML snippet

No response

Anything in the logs that might be useful for us?

2023-10-21 18:57:26.516 DEBUG (MainThread) [zigpy.serial] Opening a serial connection to 'socket://10.9.209.169:8888' (115200 baudrate)
2023-10-21 18:57:27.528 DEBUG (MainThread) [bellows.ezsp] Resetting EZSP
2023-10-21 18:57:27.529 DEBUG (MainThread) [bellows.uart] Resetting ASH
2023-10-21 18:57:27.529 DEBUG (MainThread) [bellows.uart] Sending: b'1ac038bc7e'
2023-10-21 18:57:28.675 DEBUG (MainThread) [bellows.uart] RSTACK Version: 2 Reason: RESET_SOFTWARE frame: b'c1020b0a527e'
2023-10-21 18:57:28.679 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (4,)
2023-10-21 18:57:28.681 DEBUG (MainThread) [bellows.uart] Sending: b'004221a850ed2c7e'
2023-10-21 18:57:28.762 DEBUG (MainThread) [bellows.uart] Data frame: b'0142a1a85c2885d52df87e'
2023-10-21 18:57:28.762 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:28.764 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received version: [8, 2, 26512]
2023-10-21 18:57:28.764 DEBUG (MainThread) [bellows.ezsp] Switching to EZSP protocol version 8
2023-10-21 18:57:28.765 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (8,)
2023-10-21 18:57:28.767 DEBUG (MainThread) [bellows.uart] Sending: b'7d314221a9542a1d8cdf7e'
2023-10-21 18:57:28.815 DEBUG (MainThread) [bellows.uart] Data frame: b'1242a1a9542a1db0c9f38a237e'
2023-10-21 18:57:28.815 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:28.816 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received version: [8, 2, 26512]
2023-10-21 18:57:28.816 DEBUG (MainThread) [bellows.ezsp] EZSP Stack Type: 2, Stack Version: 6790, Protocol version: 8
2023-10-21 18:57:28.817 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FORCE_TX_AFTER_FAILED_CCA_ATTEMPTS: 58>,)
2023-10-21 18:57:28.817 DEBUG (MainThread) [bellows.uart] Sending: b'224321a9fe2a2f243c7e'
2023-10-21 18:57:28.892 DEBUG (MainThread) [bellows.uart] Data frame: b'2343a1a9fe2a22b23ad07e'
2023-10-21 18:57:28.893 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:28.895 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.ERROR_INVALID_ID: 55>, b'']
2023-10-21 18:57:28.896 DEBUG (MainThread) [bellows.ezsp] Setting value VALUE_FORCE_TX_AFTER_FAILED_CCA_ATTEMPTS = 1 (old value None)
2023-10-21 18:57:28.897 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setValue: (<EzspValueId.VALUE_FORCE_TX_AFTER_FAILED_CCA_ATTEMPTS: 58>, b'\x01')
2023-10-21 18:57:28.898 DEBUG (MainThread) [bellows.uart] Sending: b'334021a9ff2a2fb35877a77e'
2023-10-21 18:57:28.930 DEBUG (MainThread) [bellows.uart] Data frame: b'3440a1a9ff2a15fe7b7e'
2023-10-21 18:57:28.930 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:28.932 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:28.933 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>,)
2023-10-21 18:57:28.934 DEBUG (MainThread) [bellows.uart] Sending: b'444121a9062a0fd3d27e'
2023-10-21 18:57:28.976 DEBUG (MainThread) [bellows.uart] Data frame: b'4541a1a9062a15b55945177e'
2023-10-21 18:57:28.977 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:28.978 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 7]
2023-10-21 18:57:28.979 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_SOURCE_ROUTE_TABLE_SIZE = 200 (old value 7)
2023-10-21 18:57:28.979 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>, 200)
2023-10-21 18:57:28.980 DEBUG (MainThread) [bellows.uart] Sending: b'554621a9072a0f7a5952917e'
2023-10-21 18:57:29.048 DEBUG (MainThread) [bellows.uart] Data frame: b'5646a1a9072a15ea907e'
2023-10-21 18:57:29.049 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:29.050 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.050 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>,)
2023-10-21 18:57:29.051 DEBUG (MainThread) [bellows.uart] Sending: b'664721a9062a06c10f7e'
2023-10-21 18:57:29.103 DEBUG (MainThread) [bellows.uart] Data frame: b'6747a1a9062a15ba59b4797e'
2023-10-21 18:57:29.104 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:29.105 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 8]
2023-10-21 18:57:29.108 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_END_DEVICE_POLL_TIMEOUT = 8 (old value 8)
2023-10-21 18:57:29.108 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>, 8)
2023-10-21 18:57:29.109 DEBUG (MainThread) [bellows.uart] Sending: b'774421a9072a06ba5934697e'
2023-10-21 18:57:29.156 DEBUG (MainThread) [bellows.uart] Data frame: b'7044a1a9072a15ae037e'
2023-10-21 18:57:29.156 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:29.159 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.161 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>,)
2023-10-21 18:57:29.164 DEBUG (MainThread) [bellows.uart] Sending: b'004521a9062a2dcd797e'
2023-10-21 18:57:29.210 DEBUG (MainThread) [bellows.uart] Data frame: b'0145a1a9062a159e5843717e'
2023-10-21 18:57:29.211 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:29.212 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 300]
2023-10-21 18:57:29.213 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S = 90 (old value 300)
2023-10-21 18:57:29.214 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>, 90)
2023-10-21 18:57:29.215 DEBUG (MainThread) [bellows.uart] Sending: b'7d314a21a9072a2de859163f7e'
2023-10-21 18:57:29.262 DEBUG (MainThread) [bellows.uart] Data frame: b'124aa1a9072a15fd597e'
2023-10-21 18:57:29.262 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:29.264 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.265 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>,)
2023-10-21 18:57:29.266 DEBUG (MainThread) [bellows.uart] Sending: b'224b21a9062a07c6e77e'
2023-10-21 18:57:29.318 DEBUG (MainThread) [bellows.uart] Data frame: b'234ba1a9062a150a52c99e7e'
2023-10-21 18:57:29.318 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:29.320 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 3000]
2023-10-21 18:57:29.321 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 (old value 3000)
2023-10-21 18:57:29.321 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>, 7680)
2023-10-21 18:57:29.322 DEBUG (MainThread) [bellows.uart] Sending: b'334821a9072a07b247ab8e7e'
2023-10-21 18:57:29.370 DEBUG (MainThread) [bellows.uart] Data frame: b'3448a1a9072a15b9ca7e'
2023-10-21 18:57:29.371 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:29.372 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.373 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>,)
2023-10-21 18:57:29.374 DEBUG (MainThread) [bellows.uart] Sending: b'444921a9062a19ac677e'
2023-10-21 18:57:29.430 DEBUG (MainThread) [bellows.uart] Data frame: b'4549a1a9062a15b259c25a7e'
2023-10-21 18:57:29.430 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:29.432 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 0]
2023-10-21 18:57:29.432 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_STACK_PROFILE = 2 (old value 0)
2023-10-21 18:57:29.433 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>, 2)
2023-10-21 18:57:29.434 DEBUG (MainThread) [bellows.uart] Sending: b'554e21a9072a19b05944177e'
2023-10-21 18:57:29.478 DEBUG (MainThread) [bellows.uart] Data frame: b'564ea1a9072a15e7d27e'
2023-10-21 18:57:29.478 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:29.480 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.481 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SUPPORTED_NETWORKS: 45>,)
2023-10-21 18:57:29.481 DEBUG (MainThread) [bellows.uart] Sending: b'664f21a9062a381bd07e'
2023-10-21 18:57:29.530 DEBUG (MainThread) [bellows.uart] Data frame: b'674fa1a9062a15b359103b7e'
2023-10-21 18:57:29.531 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:29.532 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 1]
2023-10-21 18:57:29.532 DEBUG (MainThread) [bellows.ezsp] Current config CONFIG_SUPPORTED_NETWORKS = 1 exceeds the default of 1, skipping
2023-10-21 18:57:29.532 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>,)
2023-10-21 18:57:29.533 DEBUG (MainThread) [bellows.uart] Sending: b'774c21a9062a7d33cf237e'
2023-10-21 18:57:29.585 DEBUG (MainThread) [bellows.uart] Data frame: b'704ca1a9062a15ba5980837e'
2023-10-21 18:57:29.586 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:29.587 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 8]
2023-10-21 18:57:29.588 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_MULTICAST_TABLE_SIZE = 16 (old value 8)
2023-10-21 18:57:29.588 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>, 16)
2023-10-21 18:57:29.590 DEBUG (MainThread) [bellows.uart] Sending: b'004d21a9072a7d33a25955597e'
2023-10-21 18:57:29.687 DEBUG (MainThread) [bellows.uart] Data frame: b'014da1a9072a15dae17e'
2023-10-21 18:57:29.687 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:29.689 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.697 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>,)
2023-10-21 18:57:29.706 DEBUG (MainThread) [bellows.uart] Sending: b'7d315221a9062a0ca4e57e'
2023-10-21 18:57:29.762 DEBUG (MainThread) [bellows.uart] Data frame: b'1252a1a9062a15b25994cd7e'
2023-10-21 18:57:29.763 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:29.764 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 0]
2023-10-21 18:57:29.765 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 (old value 0)
2023-10-21 18:57:29.765 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>, 2)
2023-10-21 18:57:29.771 DEBUG (MainThread) [bellows.uart] Sending: b'225321a9072a0cb0599d247e'
2023-10-21 18:57:29.834 DEBUG (MainThread) [bellows.uart] Data frame: b'2353a1a9072a154ed37e'
2023-10-21 18:57:29.834 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:29.836 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.837 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2023-10-21 18:57:29.838 DEBUG (MainThread) [bellows.uart] Sending: b'335021a9062a7d3873057e'
2023-10-21 18:57:29.889 DEBUG (MainThread) [bellows.uart] Data frame: b'3450a1a9062a15b75919ea7e'
2023-10-21 18:57:29.889 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:29.890 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 5]
2023-10-21 18:57:29.891 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_SECURITY_LEVEL = 5 (old value 5)
2023-10-21 18:57:29.891 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>, 5)
2023-10-21 18:57:29.892 DEBUG (MainThread) [bellows.uart] Sending: b'445121a9072a7d38b759b61b7e'
2023-10-21 18:57:29.943 DEBUG (MainThread) [bellows.uart] Data frame: b'4551a1a9072a15d7ac7e'
2023-10-21 18:57:29.943 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:29.945 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:29.946 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>,)
2023-10-21 18:57:29.947 DEBUG (MainThread) [bellows.uart] Sending: b'555621a9062a106dd37e'
2023-10-21 18:57:30.042 DEBUG (MainThread) [bellows.uart] Data frame: b'5656a1a9062a15ba59d13f7e'
2023-10-21 18:57:30.042 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:30.044 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 8]
2023-10-21 18:57:30.045 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_ADDRESS_TABLE_SIZE = 16 (old value 8)
2023-10-21 18:57:30.045 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>, 16)
2023-10-21 18:57:30.046 DEBUG (MainThread) [bellows.uart] Sending: b'665721a9072a10a259026c7e'
2023-10-21 18:57:30.179 DEBUG (MainThread) [bellows.uart] Data frame: b'6757a1a9072a1554587e'
2023-10-21 18:57:30.180 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:30.181 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:30.182 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>,)
2023-10-21 18:57:30.183 DEBUG (MainThread) [bellows.uart] Sending: b'775421a9062a37bc037e'
2023-10-21 18:57:30.223 DEBUG (MainThread) [bellows.uart] Data frame: b'7054a1a9062a15b0594c267e'
2023-10-21 18:57:30.224 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:30.224 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 2]
2023-10-21 18:57:30.225 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD = 2 (old value 2)
2023-10-21 18:57:30.225 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>, 2)
2023-10-21 18:57:30.225 DEBUG (MainThread) [bellows.uart] Sending: b'005521a9072a37b05949207e'
2023-10-21 18:57:30.278 DEBUG (MainThread) [bellows.uart] Data frame: b'0155a1a9072a15cd277e'
2023-10-21 18:57:30.278 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:30.279 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:30.280 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_KEY_TABLE_SIZE: 30>,)
2023-10-21 18:57:30.281 DEBUG (MainThread) [bellows.uart] Sending: b'7d315a21a9062a0bd9407e'
2023-10-21 18:57:30.337 DEBUG (MainThread) [bellows.uart] Data frame: b'125aa1a9062a15be59cf7a7e'
2023-10-21 18:57:30.337 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:30.339 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 12]
2023-10-21 18:57:30.340 DEBUG (MainThread) [bellows.ezsp] Current config CONFIG_KEY_TABLE_SIZE = 12 exceeds the default of 4, skipping
2023-10-21 18:57:30.340 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MAX_END_DEVICE_CHILDREN: 17>,)
2023-10-21 18:57:30.341 DEBUG (MainThread) [bellows.uart] Sending: b'225b21a9062a04ec007e'
2023-10-21 18:57:30.390 DEBUG (MainThread) [bellows.uart] Data frame: b'235ba1a9062a159259d4037e'
2023-10-21 18:57:30.391 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:30.392 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 32]
2023-10-21 18:57:30.393 DEBUG (MainThread) [bellows.ezsp] Current config CONFIG_MAX_END_DEVICE_CHILDREN = 32 exceeds the default of 32, skipping
2023-10-21 18:57:30.393 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>,)
2023-10-21 18:57:30.394 DEBUG (MainThread) [bellows.uart] Sending: b'335821a9062a3f2ac27e'
2023-10-21 18:57:30.438 DEBUG (MainThread) [bellows.uart] Data frame: b'3458a1a9062a15b259f8c57e'
2023-10-21 18:57:30.439 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:30.440 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 0]
2023-10-21 18:57:30.441 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_APPLICATION_ZDO_FLAGS = 3 (old value 0)
2023-10-21 18:57:30.441 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>, <EmberZdoConfigurationFlags.APP_RECEIVES_SUPPORTED_ZDO_REQUESTS|APP_HANDLES_UNSUPPORTED_ZDO_REQUESTS: 3>)
2023-10-21 18:57:30.442 DEBUG (MainThread) [bellows.uart] Sending: b'445921a9072a3fb15901317e'
2023-10-21 18:57:30.546 DEBUG (MainThread) [bellows.uart] Data frame: b'4559a1a9072a15daee7e'
2023-10-21 18:57:30.547 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:30.547 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:30.547 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2023-10-21 18:57:30.548 DEBUG (MainThread) [bellows.uart] Sending: b'555e21a9062a1420157e'
2023-10-21 18:57:30.712 DEBUG (MainThread) [bellows.uart] Data frame: b'565ea1a9062a15f2594b807e'
2023-10-21 18:57:30.713 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:30.715 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 64]
2023-10-21 18:57:30.739 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_PACKET_BUFFER_COUNT = 255 (old value 64)
2023-10-21 18:57:30.740 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>, 255)
2023-10-21 18:57:30.763 DEBUG (MainThread) [bellows.uart] Sending: b'665f21a9072a144d59c0fa7e'
2023-10-21 18:57:30.837 DEBUG (MainThread) [bellows.uart] Data frame: b'675fa1a9072a15591a7e'
2023-10-21 18:57:30.837 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:30.839 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:30.840 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2023-10-21 18:57:30.841 DEBUG (MainThread) [bellows.uart] Sending: b'775c21a94c2ab2aa7e'
2023-10-21 18:57:30.886 DEBUG (MainThread) [bellows.uart] Data frame: b'705ca1a94c2a1554997e'
2023-10-21 18:57:30.886 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:30.888 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received networkState: [<EmberNetworkStatus.NO_NETWORK: 0>]
2023-10-21 18:57:30.889 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkInit: (0,)
2023-10-21 18:57:30.892 DEBUG (MainThread) [bellows.uart] Sending: b'005d21a9432a15b20c4b7e'
2023-10-21 18:57:30.941 DEBUG (MainThread) [bellows.uart] Data frame: b'015da5a9432a15880e7e'
2023-10-21 18:57:30.942 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:30.942 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received networkInit: [<EmberStatus.SUCCESS: 0>]
2023-10-21 18:57:30.942 DEBUG (MainThread) [bellows.uart] Data frame: b'115db1a94d2a85aa917e'
2023-10-21 18:57:30.942 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:30.943 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received stackStatusHandler: [<EmberStatus.NETWORK_UP: 144>]
2023-10-21 18:57:30.943 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2023-10-21 18:57:30.943 DEBUG (MainThread) [bellows.uart] Sending: b'126221a97c2a68607e'
2023-10-21 18:57:30.988 DEBUG (MainThread) [bellows.uart] Data frame: b'2262a1a97c2a15b3b14fceba66995e85745533a0edce678bfd3e9c8ee0637e'
2023-10-21 18:57:30.988 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:30.990 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getNetworkParameters: [<EmberStatus.SUCCESS: 0>, <EmberNodeType.COORDINATOR: 1>, EmberNetworkParameters(extendedPanId=cc:cc:cc:cc:9f:84:db:e8, panId=0x1be8, radioTxPower=20, radioChannel=11, joinMethod=<EmberJoinMethod.USE_MAC_ASSOCIATION: 0>, nwkManagerId=0x0000, nwkUpdateId=0, channels=<Channels.ALL_CHANNELS: 134215680>)]
2023-10-21 18:57:30.991 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2023-10-21 18:57:30.992 DEBUG (MainThread) [bellows.uart] Sending: b'236321a9732ab8237e'
2023-10-21 18:57:31.041 DEBUG (MainThread) [bellows.uart] Data frame: b'3363a1a9732a15b2112e7e'
2023-10-21 18:57:31.042 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:31.043 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getNodeId: [0x0000]
2023-10-21 18:57:31.044 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2023-10-21 18:57:31.057 DEBUG (MainThread) [bellows.uart] Sending: b'346021a9722ab7057e'
2023-10-21 18:57:31.092 DEBUG (MainThread) [bellows.uart] Data frame: b'4460a1a9722afb0d086ab5cf9fc53ab27e'
2023-10-21 18:57:31.092 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:31.094 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getEui64: [90:35:ea:ff:fe:51:bf:ee]
2023-10-21 18:57:31.096 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2023-10-21 18:57:31.097 DEBUG (MainThread) [bellows.uart] Sending: b'456121a9062a7d383c6d7e'
2023-10-21 18:57:31.150 DEBUG (MainThread) [bellows.uart] Data frame: b'5561a1a9062a15b7590be37e'
2023-10-21 18:57:31.151 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:31.152 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 5]
2023-10-21 18:57:31.154 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2023-10-21 18:57:31.155 DEBUG (MainThread) [bellows.uart] Sending: b'566621a93e2a16961f7e'
2023-10-21 18:57:31.205 DEBUG (MainThread) [bellows.uart] Data frame: b'6666a1a93e2a15b15997eb65950a7a9218d186ebd2918f50795962692d7f3fa7ebcdde6f8fffc7dbd5d2694f027e'
2023-10-21 18:57:31.206 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:31.209 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getKey: [<EmberStatus.SUCCESS: 0>, EmberKeyStruct(bitmask=<EmberKeyStructBitmask.KEY_HAS_SEQUENCE_NUMBER|KEY_HAS_OUTGOING_FRAME_COUNTER: 3>, type=<EmberKeyType.CURRENT_NETWORK_KEY: 3>, key=a1:40:3f:5f:e8:db:84:9f:a1:40:3f:5f:e8:db:84:9f, outgoingFrameCounter=30531585, incomingFrameCounter=0, sequenceNumber=0, partnerEUI64=00:00:00:00:00:00:00:00)]
2023-10-21 18:57:31.211 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2023-10-21 18:57:31.220 DEBUG (MainThread) [bellows.uart] Sending: b'676721a93e2a14127d317e'
2023-10-21 18:57:31.258 DEBUG (MainThread) [bellows.uart] Data frame: b'7767a1a93e2a15a85995104ccd17f72cdd224bc28ca004eecdff6359f27e3fa7ebcdde8130ae39243fe7f9b91d7e'
2023-10-21 18:57:31.259 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:31.262 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getKey: [<EmberStatus.SUCCESS: 0>, EmberKeyStruct(bitmask=<EmberKeyStructBitmask.KEY_HAS_OUTGOING_FRAME_COUNTER|KEY_HAS_PARTNER_EUI64|KEY_IS_AUTHORIZED: 26>, type=<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>, key=5a:69:67:42:65:65:41:6c:6c:69:61:6e:63:65:30:39, outgoingFrameCounter=970752, incomingFrameCounter=0, sequenceNumber=0, partnerEUI64=90:35:ea:ff:fe:51:bf:ee)]
2023-10-21 18:57:31.267 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2023-10-21 18:57:31.268 DEBUG (MainThread) [bellows.uart] Sending: b'706421a93d2a4f407e'
2023-10-21 18:57:31.308 DEBUG (MainThread) [bellows.uart] Data frame: b'0064a1a93d2a15ce597af57454aa787c0cd9717e'
2023-10-21 18:57:31.308 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:31.309 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getCurrentSecurityState: [<EmberStatus.SUCCESS: 0>, EmberCurrentSecurityState(bitmask=<EmberCurrentSecurityBitmask.GLOBAL_LINK_KEY|HAVE_TRUST_CENTER_LINK_KEY|104: 124>, trustCenterLongAddress=90:35:ea:ff:fe:51:bf:ee)]
2023-10-21 18:57:31.311 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2023-10-21 18:57:31.313 DEBUG (MainThread) [bellows.uart] Sending: b'016521a95f2a1427357e'
2023-10-21 18:57:31.361 DEBUG (MainThread) [bellows.uart] Data frame: b'1165a1a95f2a054da66bb5da55aa6db663b1d85412319823f47e'
2023-10-21 18:57:31.362 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:31.363 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getMfgToken: [b'\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff']
2023-10-21 18:57:31.364 DEBUG (MainThread) [bellows.ezsp] Read MFG_STRING token: b'\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff'
2023-10-21 18:57:31.364 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2023-10-21 18:57:31.367 DEBUG (MainThread) [bellows.uart] Sending: b'126a21a95f2a173dac7e'
2023-10-21 18:57:31.416 DEBUG (MainThread) [bellows.uart] Data frame: b'226aa1a95f2a054da66bb5da55aa6db663b1d85412319893f97e'
2023-10-21 18:57:31.416 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:31.418 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getMfgToken: [b'\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff']
2023-10-21 18:57:31.420 DEBUG (MainThread) [bellows.ezsp] Read MFG_BOARD_NAME token: b'\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff'
2023-10-21 18:57:31.421 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2023-10-21 18:57:31.424 DEBUG (MainThread) [bellows.uart] Sending: b'236b21a9fe2a04311e7e'
2023-10-21 18:57:31.472 DEBUG (MainThread) [bellows.uart] Data frame: b'336ba1a9fe2a15b5cc954c22a35538ff8b7e'
2023-10-21 18:57:31.473 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:31.474 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\x95\x01\x06\x07\t\x00\xaa']
2023-10-21 18:57:31.475 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2023-10-21 18:57:31.478 DEBUG (MainThread) [bellows.uart] Sending: b'346821a95f2a1998f17e'
2023-10-21 18:57:31.524 DEBUG (MainThread) [bellows.uart] Data frame: b'4468a1a95f2a1d4da66bb5da55aa6df6ab7e'
2023-10-21 18:57:31.524 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:31.526 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getMfgToken: [b'\xff\xff\xff\xff\xff\xff\xff\xff']
2023-10-21 18:57:31.535 DEBUG (MainThread) [zigpy.application] Network info: NetworkInfo(extended_pan_id=cc:cc:cc:cc:9f:84:db:e8, pan_id=0x1BE8, nwk_update_id=0, nwk_manager_id=0x0000, channel=11, channel_mask=<Channels.ALL_CHANNELS: 134215680>, security_level=5, network_key=Key(key=a1:40:3f:5f:e8:db:84:9f:a1:40:3f:5f:e8:db:84:9f, tx_counter=30531585, rx_counter=0, seq=0, partner_ieee=ff:ff:ff:ff:ff:ff:ff:ff), tc_link_key=Key(key=5a:69:67:42:65:65:41:6c:6c:69:61:6e:63:65:30:39, tx_counter=970752, rx_counter=0, seq=0, partner_ieee=90:35:ea:ff:fe:51:bf:ee), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_burn_userdata_custom_eui64': True, 'can_rewrite_custom_eui64': False}}, source='bellows@0.36.7')
2023-10-21 18:57:31.536 DEBUG (MainThread) [zigpy.application] Node info: NodeInfo(nwk=0x0000, ieee=90:35:ea:ff:fe:51:bf:ee, logical_type=<LogicalType.Coordinator: 0>)
2023-10-21 18:57:31.537 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addEndpoint: (1, 260, <DeviceType.IAS_CONTROL: 1024>, 0, 5, 4, [0, 6, 10, 25, 1281], [1, 32, 1280, 1282])
2023-10-21 18:57:31.538 DEBUG (MainThread) [bellows.uart] Sending: b'456921a9562a14b658944e25af5192499a4e2dabf4ce668efcc64389fc7b3da201ad7e'
2023-10-21 18:57:31.581 DEBUG (MainThread) [bellows.uart] Data frame: b'5569a1a9562a2d1cbc7e'
2023-10-21 18:57:31.582 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:31.583 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received addEndpoint: [<EzspStatus.ERROR_INVALID_CALL: 56>]
2023-10-21 18:57:32.586 DEBUG (MainThread) [bellows.ezsp] Resetting EZSP
2023-10-21 18:57:32.586 DEBUG (MainThread) [bellows.uart] Resetting ASH
2023-10-21 18:57:32.587 DEBUG (MainThread) [bellows.uart] Sending: b'1ac038bc7e'
2023-10-21 18:57:33.740 DEBUG (MainThread) [bellows.uart] RSTACK Version: 2 Reason: RESET_SOFTWARE frame: b'c1020b0a527e'
2023-10-21 18:57:33.741 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (8,)
2023-10-21 18:57:33.742 DEBUG (MainThread) [bellows.uart] Sending: b'006e21a9542a1d3d2e7e'
2023-10-21 18:57:33.808 DEBUG (MainThread) [bellows.uart] Data frame: b'016ea1a9542a1db0c9f3bd967e'
2023-10-21 18:57:33.808 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:33.808 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received version: [8, 2, 26512]
2023-10-21 18:57:33.809 DEBUG (MainThread) [bellows.ezsp] EZSP Stack Type: 2, Stack Version: 6790, Protocol version: 8
2023-10-21 18:57:33.809 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FORCE_TX_AFTER_FAILED_CCA_ATTEMPTS: 58>,)
2023-10-21 18:57:33.814 DEBUG (MainThread) [bellows.uart] Sending: b'7d316f21a9fe2a2f9bd87e'
2023-10-21 18:57:33.855 DEBUG (MainThread) [bellows.uart] Data frame: b'126fa1a9fe2a22b207427e'
2023-10-21 18:57:33.855 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:33.856 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.ERROR_INVALID_ID: 55>, b'']
2023-10-21 18:57:33.857 DEBUG (MainThread) [bellows.ezsp] Setting value VALUE_FORCE_TX_AFTER_FAILED_CCA_ATTEMPTS = 1 (old value None)
2023-10-21 18:57:33.857 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setValue: (<EzspValueId.VALUE_FORCE_TX_AFTER_FAILED_CCA_ATTEMPTS: 58>, b'\x01')
2023-10-21 18:57:33.858 DEBUG (MainThread) [bellows.uart] Sending: b'226c21a9ff2a2fb358a5a57e'
2023-10-21 18:57:33.906 DEBUG (MainThread) [bellows.uart] Data frame: b'236ca1a9ff2a15eeaf7e'
2023-10-21 18:57:33.907 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:33.908 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:33.909 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>,)
2023-10-21 18:57:33.910 DEBUG (MainThread) [bellows.uart] Sending: b'336d21a9062a0f701c7e'
2023-10-21 18:57:33.956 DEBUG (MainThread) [bellows.uart] Data frame: b'346da1a9062a15b5596f307e'
2023-10-21 18:57:33.956 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:33.957 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 7]
2023-10-21 18:57:33.958 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_SOURCE_ROUTE_TABLE_SIZE = 200 (old value 7)
2023-10-21 18:57:33.958 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>, 200)
2023-10-21 18:57:33.959 DEBUG (MainThread) [bellows.uart] Sending: b'447221a9072a0f7a59a3fd7e'
2023-10-21 18:57:34.029 DEBUG (MainThread) [bellows.uart] Data frame: b'4572a1a9072a152c447e'
2023-10-21 18:57:34.030 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:34.031 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.032 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>,)
2023-10-21 18:57:34.033 DEBUG (MainThread) [bellows.uart] Sending: b'557321a9062a06692d7e'
2023-10-21 18:57:34.081 DEBUG (MainThread) [bellows.uart] Data frame: b'5673a1a9062a15ba59e2e97e'
2023-10-21 18:57:34.082 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:34.083 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 8]
2023-10-21 18:57:34.084 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_END_DEVICE_POLL_TIMEOUT = 8 (old value 8)
2023-10-21 18:57:34.085 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>, 8)
2023-10-21 18:57:34.088 DEBUG (MainThread) [bellows.uart] Sending: b'667021a9072a06ba59c5057e'
2023-10-21 18:57:34.140 DEBUG (MainThread) [bellows.uart] Data frame: b'6770a1a9072a15a9117e'
2023-10-21 18:57:34.140 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:34.141 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.141 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>,)
2023-10-21 18:57:34.142 DEBUG (MainThread) [bellows.uart] Sending: b'777121a9062a2d79717e'
2023-10-21 18:57:34.190 DEBUG (MainThread) [bellows.uart] Data frame: b'7071a1a9062a159e584a387e'
2023-10-21 18:57:34.191 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:34.193 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 300]
2023-10-21 18:57:34.194 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S = 90 (old value 300)
2023-10-21 18:57:34.194 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>, 90)
2023-10-21 18:57:34.195 DEBUG (MainThread) [bellows.uart] Sending: b'007621a9072a2de859f9897e'
2023-10-21 18:57:34.242 DEBUG (MainThread) [bellows.uart] Data frame: b'0176a1a9072a1536cf7e'
2023-10-21 18:57:34.242 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:34.243 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.244 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>,)
2023-10-21 18:57:34.245 DEBUG (MainThread) [bellows.uart] Sending: b'7d317721a9062a0763877e'
2023-10-21 18:57:34.298 DEBUG (MainThread) [bellows.uart] Data frame: b'1277a1a9062a150a5281d47e'
2023-10-21 18:57:34.298 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:34.299 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 3000]
2023-10-21 18:57:34.300 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 (old value 3000)
2023-10-21 18:57:34.300 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>, 7680)
2023-10-21 18:57:34.303 DEBUG (MainThread) [bellows.uart] Sending: b'227421a9072a07b24744387e'
2023-10-21 18:57:34.351 DEBUG (MainThread) [bellows.uart] Data frame: b'2374a1a9072a15b39a7e'
2023-10-21 18:57:34.351 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:34.352 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.353 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>,)
2023-10-21 18:57:34.356 DEBUG (MainThread) [bellows.uart] Sending: b'337521a9062a19152d7e'
2023-10-21 18:57:34.423 DEBUG (MainThread) [bellows.uart] Data frame: b'3475a1a9062a15b259d5c97e'
2023-10-21 18:57:34.423 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:34.425 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 0]
2023-10-21 18:57:34.426 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_STACK_PROFILE = 2 (old value 0)
2023-10-21 18:57:34.426 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>, 2)
2023-10-21 18:57:34.429 DEBUG (MainThread) [bellows.uart] Sending: b'447a21a9072a19b059b57b7e'
2023-10-21 18:57:34.464 DEBUG (MainThread) [bellows.uart] Data frame: b'457aa1a9072a1521067e'
2023-10-21 18:57:34.464 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:34.465 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.465 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SUPPORTED_NETWORKS: 45>,)
2023-10-21 18:57:34.466 DEBUG (MainThread) [bellows.uart] Sending: b'557b21a9062a38b3f27e'
2023-10-21 18:57:34.535 DEBUG (MainThread) [bellows.uart] Data frame: b'567ba1a9062a15b35946ab7e'
2023-10-21 18:57:34.535 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:34.537 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 1]
2023-10-21 18:57:34.539 DEBUG (MainThread) [bellows.ezsp] Current config CONFIG_SUPPORTED_NETWORKS = 1 exceeds the default of 1, skipping
2023-10-21 18:57:34.539 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>,)
2023-10-21 18:57:34.541 DEBUG (MainThread) [bellows.uart] Sending: b'667821a9062a7d3369147e'
2023-10-21 18:57:34.571 DEBUG (MainThread) [bellows.uart] Data frame: b'6778a1a9062a15ba592b677e'
2023-10-21 18:57:34.571 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:34.572 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 8]
2023-10-21 18:57:34.573 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_MULTICAST_TABLE_SIZE = 16 (old value 8)
2023-10-21 18:57:34.573 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>, 16)
2023-10-21 18:57:34.574 DEBUG (MainThread) [bellows.uart] Sending: b'777921a9072a7d33a25906987e'
2023-10-21 18:57:34.640 DEBUG (MainThread) [bellows.uart] Data frame: b'7079a1a9072a15cfcc7e'
2023-10-21 18:57:34.640 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:34.642 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.643 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>,)
2023-10-21 18:57:34.643 DEBUG (MainThread) [bellows.uart] Sending: b'007d5e21a9062a0c15147e'
2023-10-21 18:57:34.697 DEBUG (MainThread) [bellows.uart] Data frame: b'017ea1a9062a15b25980a87e'
2023-10-21 18:57:34.697 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:34.699 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 0]
2023-10-21 18:57:34.700 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 (old value 0)
2023-10-21 18:57:34.700 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>, 2)
2023-10-21 18:57:34.701 DEBUG (MainThread) [bellows.uart] Sending: b'7d317f21a9072a0cb0592ebd7e'
2023-10-21 18:57:34.781 DEBUG (MainThread) [bellows.uart] Data frame: b'127fa1a9072a1591d47e'
2023-10-21 18:57:34.781 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:34.783 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.784 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2023-10-21 18:57:34.786 DEBUG (MainThread) [bellows.uart] Sending: b'227c21a9062a7d38c2f47e'
2023-10-21 18:57:34.832 DEBUG (MainThread) [bellows.uart] Data frame: b'237ca1a9062a15b75991607e'
2023-10-21 18:57:34.832 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:34.833 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 5]
2023-10-21 18:57:34.833 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_SECURITY_LEVEL = 5 (old value 5)
2023-10-21 18:57:34.833 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>, 5)
2023-10-21 18:57:34.833 DEBUG (MainThread) [bellows.uart] Sending: b'337d5d21a9072a7d38b759c6b47e'
2023-10-21 18:57:34.871 DEBUG (MainThread) [bellows.uart] Data frame: b'347da1a9072a15d5477e'
2023-10-21 18:57:34.871 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:34.873 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:34.874 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>,)
2023-10-21 18:57:34.874 DEBUG (MainThread) [bellows.uart] Sending: b'440221a9062a1094fc7e'
2023-10-21 18:57:34.930 DEBUG (MainThread) [bellows.uart] Data frame: b'4502a1a9062a15ba596b8c7e'
2023-10-21 18:57:34.930 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:34.932 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 8]
2023-10-21 18:57:34.932 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_ADDRESS_TABLE_SIZE = 16 (old value 8)
2023-10-21 18:57:34.933 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>, 16)
2023-10-21 18:57:34.933 DEBUG (MainThread) [bellows.uart] Sending: b'550321a9072a10a2591f237e'
2023-10-21 18:57:34.998 DEBUG (MainThread) [bellows.uart] Data frame: b'5603a1a9072a15c3817e'
2023-10-21 18:57:34.998 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:35.000 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:35.001 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>,)
2023-10-21 18:57:35.001 DEBUG (MainThread) [bellows.uart] Sending: b'660021a9062a37452c7e'
2023-10-21 18:57:35.054 DEBUG (MainThread) [bellows.uart] Data frame: b'6700a1a9062a15b0596a7a7e'
2023-10-21 18:57:35.055 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-21 18:57:35.056 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 2]
2023-10-21 18:57:35.057 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD = 2 (old value 2)
2023-10-21 18:57:35.057 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>, 2)
2023-10-21 18:57:35.058 DEBUG (MainThread) [bellows.uart] Sending: b'770121a9072a37b05997597e'
2023-10-21 18:57:35.111 DEBUG (MainThread) [bellows.uart] Data frame: b'7001a1a9072a1587127e'
2023-10-21 18:57:35.111 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-21 18:57:35.112 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:35.113 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_KEY_TABLE_SIZE: 30>,)
2023-10-21 18:57:35.114 DEBUG (MainThread) [bellows.uart] Sending: b'000621a9062a0b2d2d7e'
2023-10-21 18:57:35.160 DEBUG (MainThread) [bellows.uart] Data frame: b'0106a1a9062a15be596b137e'
2023-10-21 18:57:35.160 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-21 18:57:35.161 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 12]
2023-10-21 18:57:35.161 DEBUG (MainThread) [bellows.ezsp] Current config CONFIG_KEY_TABLE_SIZE = 12 exceeds the default of 4, skipping
2023-10-21 18:57:35.161 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MAX_END_DEVICE_CHILDREN: 17>,)
2023-10-21 18:57:35.162 DEBUG (MainThread) [bellows.uart] Sending: b'7d310721a9062a0416787e'
2023-10-21 18:57:35.213 DEBUG (MainThread) [bellows.uart] Data frame: b'1207a1a9062a15925911f17e'
2023-10-21 18:57:35.213 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-21 18:57:35.215 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 32]
2023-10-21 18:57:35.215 DEBUG (MainThread) [bellows.ezsp] Current config CONFIG_MAX_END_DEVICE_CHILDREN = 32 exceeds the default of 32, skipping
2023-10-21 18:57:35.216 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>,)
2023-10-21 18:57:35.216 DEBUG (MainThread) [bellows.uart] Sending: b'220421a9062a3fdeaf7e'
2023-10-21 18:57:35.269 DEBUG (MainThread) [bellows.uart] Data frame: b'2304a1a9062a15b259c0437e'
2023-10-21 18:57:35.269 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-21 18:57:35.271 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 0]
2023-10-21 18:57:35.272 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_APPLICATION_ZDO_FLAGS = 3 (old value 0)
2023-10-21 18:57:35.272 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>, <EmberZdoConfigurationFlags.APP_RECEIVES_SUPPORTED_ZDO_REQUESTS|APP_HANDLES_UNSUPPORTED_ZDO_REQUESTS: 3>)
2023-10-21 18:57:35.273 DEBUG (MainThread) [bellows.uart] Sending: b'330521a9072a3fb159c1927e'
2023-10-21 18:57:35.322 DEBUG (MainThread) [bellows.uart] Data frame: b'3405a1a9072a159d997e'
2023-10-21 18:57:35.323 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-21 18:57:35.325 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setConfigurationValue: [<EzspStatus.SUCCESS: 0>]
2023-10-21 18:57:35.326 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2023-10-21 18:57:35.327 DEBUG (MainThread) [bellows.uart] Sending: b'440a21a9062a14d93a7e'
2023-10-21 18:57:35.377 DEBUG (MainThread) [bellows.uart] Data frame: b'450aa1a9062a15f259f1337e'
2023-10-21 18:57:35.378 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-21 18:57:35.379 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getConfigurationValue: [<EzspStatus.SUCCESS: 0>, 64]
2023-10-21 18:57:35.379 DEBUG (MainThread) [bellows.ezsp] Setting config CONFIG_PACKET_BUFFER_COUNT = 255 (old value 64)
2023-10-21 18:57:35.380 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>, 255)
2023-10-21 18:57:35.381 DEBUG (MainThread) [bellows.uart] Sending: b'550b21a9072a144d59ddb57e'
2023-10-21 18:57:35.449 DEBUG (MainThread) [bellows.uart] Data frame: b'560ba1a9542a15c95d7e'
2023-10-21 18:57:35.449 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-21 18:57:35.451 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received version: [0, 0, 0]
2023-10-21 18:57:35.451 ERROR (MainThread) [homeassistant] Error doing job: Fatal error: protocol.data_received() call failed.
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 1003, in _read_ready__data_received
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 81, in data_received
    self.frame_received(frame)
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 108, in frame_received
    self.data_frame_received(data)
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 129, in data_frame_received
    self._application.frame_received(self._randomize(data[1:-3]))
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 337, in frame_received
    self._protocol(data)
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 119, in __call__
    assert expected_id == frame_id
           ^^^^^^^^^^^^^^^^^^^^^^^
AssertionError
2023-10-21 18:57:35.464 DEBUG (MainThread) [bellows.uart] Connection lost: AssertionError()
2023-10-21 18:57:35.465 ERROR (MainThread) [bellows.uart] Lost serial connection: AssertionError()
2023-10-21 18:57:35.465 DEBUG (MainThread) [bellows.ezsp] socket://10.9.209.169:8888 connection lost unexpectedly: 
2023-10-21 18:57:35.465 INFO (MainThread) [bellows.ezsp] NCP entered failed state. No application handler registered, ignoring...
2023-10-21 18:57:40.138 ERROR (MainThread) [homeassistant.components.tailscale] Unexpected error fetching tailscale data: 4 validation errors for Devices
devices -> 13626478649291633 -> clientConnectivity -> derp
  field required (type=value_error.missing)
devices -> 25836222639824341 -> clientConnectivity -> derp
  field required (type=value_error.missing)
devices -> 68135069901598913 -> clientConnectivity -> derp
  field required (type=value_error.missing)
devices -> 31453849988556517 -> clientConnectivity -> derp
  field required (type=value_error.missing)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/update_coordinator.py", line 290, in _async_refresh
    self.data = await self._async_update_data()
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/tailscale/coordinator.py", line 37, in _async_update_data
    return await self.tailscale.devices()
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/tailscale/tailscale.py", line 116, in devices
    return Devices.parse_obj(data).devices
           ^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/pydantic/main.py", line 526, in parse_obj
    return cls(**obj)
           ^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/pydantic/main.py", line 341, in __init__
    raise validation_error
pydantic.error_wrappers.ValidationError: 4 validation errors for Devices
devices -> 13626478649291633 -> clientConnectivity -> derp
  field required (type=value_error.missing)
devices -> 25836222639824341 -> clientConnectivity -> derp
  field required (type=value_error.missing)
devices -> 68135069901598913 -> clientConnectivity -> derp
  field required (type=value_error.missing)
devices -> 31453849988556517 -> clientConnectivity -> derp
  field required (type=value_error.missing)
2023-10-21 18:57:40.382 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 68, in command
    return await future
           ^^^^^^^^^^^^
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 198, in startup
    await self.initialize(auto_form=auto_form)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 150, in initialize
    await self.start_network()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 184, in start_network
    await self._reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 67, in command
    async with asyncio_timeout(EZSP_CMD_TIMEOUT):
  File "/usr/local/lib/python3.11/asyncio/timeouts.py", line 111, in __aexit__
    raise TimeoutError from exc_val
TimeoutError
2023-10-21 18:57:40.389 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 3 of 3)
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 68, in command
    return await future
           ^^^^^^^^^^^^
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 198, in startup
    await self.initialize(auto_form=auto_form)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 150, in initialize
    await self.start_network()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 184, in start_network
    await self._reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 67, in command
    async with asyncio_timeout(EZSP_CMD_TIMEOUT):
  File "/usr/local/lib/python3.11/asyncio/timeouts.py", line 111, in __aexit__
    raise TimeoutError from exc_val
TimeoutError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 201, in async_initialize
    await self.application_controller.startup(auto_form=True)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 201, in startup
    await self.shutdown()
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 406, in shutdown
    await self._dblistener.shutdown()
  File "/usr/local/lib/python3.11/site-packages/zigpy/appdb.py", line 187, in shutdown
    await self._set_isolation_level(None)
  File "/usr/local/lib/python3.11/site-packages/zigpy/appdb.py", line 205, in _set_isolation_level
    await self._db._execute(lambda: setattr(self._db, "isolation_level", level))
  File "/usr/local/lib/python3.11/site-packages/aiosqlite/core.py", line 126, in _execute
    raise ValueError("Connection closed")
ValueError: Connection closed
2023-10-21 18:57:40.399 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry socket://10.9.209.169:8888 for zha
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 68, in command
    return await future
           ^^^^^^^^^^^^
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 198, in startup
    await self.initialize(auto_form=auto_form)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 150, in initialize
    await self.start_network()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 184, in start_network
    await self._reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/protocol.py", line 67, in command
    async with asyncio_timeout(EZSP_CMD_TIMEOUT):
  File "/usr/local/lib/python3.11/asyncio/timeouts.py", line 111, in __aexit__
    raise TimeoutError from exc_val
TimeoutError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/config_entries.py", line 399, in async_setup
    result = await component.async_setup_entry(hass, self)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/zha/__init__.py", line 172, in async_setup_entry
    await zha_gateway.async_initialize()
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 214, in async_initialize
    raise exc
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 201, in async_initialize
    await self.application_controller.startup(auto_form=True)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 201, in startup
    await self.shutdown()
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 406, in shutdown
    await self._dblistener.shutdown()
  File "/usr/local/lib/python3.11/site-packages/zigpy/appdb.py", line 187, in shutdown
    await self._set_isolation_level(None)
  File "/usr/local/lib/python3.11/site-packages/zigpy/appdb.py", line 205, in _set_isolation_level
    await self._db._execute(lambda: setattr(self._db, "isolation_level", level))
  File "/usr/local/lib/python3.11/site-packages/aiosqlite/core.py", line 126, in _execute
    raise ValueError("Connection closed")
ValueError: Connection closed

Additional information

No response

About this issue

  • Original URL
  • State: closed
  • Created 8 months ago
  • Reactions: 1
  • Comments: 19 (2 by maintainers)

Most upvoted comments

Same issue for me, Sonoff bridge running tasmota

+1
timothyanderson82 on Oct 22, 2023

Same issue. Downgrading to 2023.10.3 resolves the problem. Suspect this is caused by #102358

+1
Sammy1Am on Oct 22, 2023

My SONOFF ZBBridge has the same issue.

+1
TYLIAO on Oct 22, 2023
Read more comments on GitHub
← core: ZHA unreliable and slow on boot (async_initialize: all attempts have failed) with Conbee 2
core: ZHA will not start in 2023.5 →