search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
71.08k stars 29.74k forks source link

Sonoff Zigbee Bridge with ZHA fails to start after upgrade to 2022.10.0 #79832

Closed blackscreener closed 1 year ago

blackscreener commented 1 year ago

The problem

Hi,

ZHA integration is not working after upgrading to 2022.10.0.

@dmulcahey, @Adminiuga, @puddly

What version of Home Assistant Core has the issue?

2022.10.0

What was the last working version of Home Assistant Core?

2022.9.7

What type of installation are you running?

Home Assistant OS

Integration causing the issue

ZHA

Link to integration documentation on our website

https://www.home-assistant.io/integrations/zha

Diagnostics information

Logger: homeassistant.components.zha.core.gateway
Source: components/zha/core/gateway.py:172
Integration: Zigbee Home Automation (documentation, issues)
First occurred: 20:13:58 (3 occurrences)
Last logged: 20:14:20

Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 1 of 3)
Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 2 of 3)
Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 3 of 3)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 172, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 144, in new
    await app.startup(auto_form=auto_form)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 125, in startup
    await self.initialize(auto_form=auto_form)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 110, in initialize
    await self.permit(0)
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 807, in permit
    await super().permit(time_s, node)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 929, in permit
    await zigpy.zdo.broadcast(
  File "/usr/local/lib/python3.10/site-packages/zigpy/device.py", line 518, in broadcast
    return await app.broadcast(
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 744, in broadcast
    await self.send_packet(
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 800, in send_packet
    raise zigpy.exceptions.DeliveryError(
zigpy.exceptions.DeliveryError: Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>

Logger: homeassistant.components.zha.core.gateway
Source: components/zha/core/gateway.py:172
Integration: Zigbee Home Automation (documentation, issues)
First occurred: 20:13:58 (3 occurrences)
Last logged: 20:14:20

Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 1 of 3)
Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 2 of 3)
Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 3 of 3)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 172, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 144, in new
    await app.startup(auto_form=auto_form)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 125, in startup
    await self.initialize(auto_form=auto_form)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 110, in initialize
    await self.permit(0)
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 807, in permit
    await super().permit(time_s, node)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 929, in permit
    await zigpy.zdo.broadcast(
  File "/usr/local/lib/python3.10/site-packages/zigpy/device.py", line 518, in broadcast
    return await app.broadcast(
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 744, in broadcast
    await self.send_packet(
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 800, in send_packet
    raise zigpy.exceptions.DeliveryError(
zigpy.exceptions.DeliveryError: Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>

Logger: zigpy.application
Source: /usr/local/lib/python3.10/site-packages/zigpy/application.py:127
First occurred: 20:13:58 (3 occurrences)
Last logged: 20:14:20

Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 125, in startup
    await self.initialize(auto_form=auto_form)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 110, in initialize
    await self.permit(0)
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 807, in permit
    await super().permit(time_s, node)
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 929, in permit
    await zigpy.zdo.broadcast(
  File "/usr/local/lib/python3.10/site-packages/zigpy/device.py", line 518, in broadcast
    return await app.broadcast(
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 744, in broadcast
    await self.send_packet(
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 800, in send_packet
    raise zigpy.exceptions.DeliveryError(
zigpy.exceptions.DeliveryError: Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

Additional information

No response

homeassistant commented 1 year ago

Hey there @dmulcahey, @adminiuga, @puddly, mind taking a look at this issue as it has been labeled with an integration (zha) you are listed as a code owner for? Thanks! (message by CodeOwnersMention)

zha documentation zha source (message by IssueLinks)

puddly commented 1 year ago

Please enable https://www.home-assistant.io/integrations/zha/#debug-logging and upload here (or email me) the log file.

blackscreener commented 1 year ago

@puddly 

2022-10-07 20:13:00.099 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration zha_custom 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-07 20:13:00.100 ERROR (SyncWorker_0) [homeassistant.loader] The custom integration 'zha_custom' does not have a version key in the manifest file and was blocked from loading. See https://developers.home-assistant.io/blog/2021/01/29/custom-integration-changes#versions for more details
2022-10-07 20:13:29.025 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_state, old_state=None, new_state=<state sensor.zha_bridge_state=unknown; icon=mdi:router-wireless, friendly_name=ZHA Bridge State @ 2022-10-07T20:13:29.025403+02:00>>
2022-10-07 20:13:30.028 INFO (MainThread) [homeassistant.bootstrap] Setting up stage 2: {'deluge', 'xiaomi_miio', 'energy', 'counter', 'light', 'binary_sensor', 'zone', 'template', 'history', 'button', 'timer', 'moon', 'notify', 'persistent_notification', 'shopping_list', 'influxdb', 'panel_custom', 'rflink', 'utility_meter', 'bthome', 'schedule', 'lovelace', 'automation', 'ble_monitor', 'my', 'google', 'cover', 'garbage_collection', 'dwains_dashboard', 'auth', 'default_config', 'vlc_telnet', 'trace', 'diagnostics', 'map', 'climate', 'cast', 'scene', 'var', 'dlna_dms', 'airly', 'blitzortung', 'image', 'logbook', 'sun', 'repairs', 'config', 'wake_on_lan', 'spotify', 'dlna_dmr', 'blueprint', 'input_text', 'launch_library', 'onboarding', 'homekit_controller', 'uptime', 'input_button', 'homeassistant_alerts', 'met', 'script', 'spotcast', 'mobile_app', 'group', 'device_tracker', 'radio_browser', 'esphome', 'person', 'input_select', 'tts', 'webostv', 'tag', 'application_credentials', 'input_number', 'media_source', 'input_datetime', 'zha', 'scheduler', 'mqtt', 'input_boolean', 'device_automation', 'switch', 'nmap_tracker', 'search', 'file_upload', 'hacs', 'co2signal', 'browser_mod', 'hardware', 'tasmota', 'sensor', 'alarmo', 'filesize', 'konnected', 'media_player', 'system_health', 'rest_command', 'analytics'}
2022-10-07 20:13:38.233 INFO (MainThread) [homeassistant.setup] Setting up zha
2022-10-07 20:13:38.234 INFO (MainThread) [homeassistant.setup] Setup of domain zha took 0.0 seconds
2022-10-07 20:13:38.258 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.adeo
2022-10-07 20:13:38.261 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.adeo.color_controller
2022-10-07 20:13:38.265 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.aduro
2022-10-07 20:13:38.267 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.aduro.adurolightncc
2022-10-07 20:13:38.268 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.aurora
2022-10-07 20:13:38.270 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.aurora.aurora_dimmer
2022-10-07 20:13:38.277 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.bitron
2022-10-07 20:13:38.279 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.bitron.thermostat
2022-10-07 20:13:38.283 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.bosch
2022-10-07 20:13:38.285 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.bosch.isw_zdl1_wp11g
2022-10-07 20:13:38.289 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.bosch.motion
2022-10-07 20:13:38.294 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite
2022-10-07 20:13:38.296 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3130
2022-10-07 20:13:38.302 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3157100
2022-10-07 20:13:38.303 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3300S
2022-10-07 20:13:38.305 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3305S
2022-10-07 20:13:38.307 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3310S
2022-10-07 20:13:38.309 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3321S
2022-10-07 20:13:38.313 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.cl_3460L
2022-10-07 20:13:38.317 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.ias
2022-10-07 20:13:38.319 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.motion
2022-10-07 20:13:38.321 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.centralite.motionandtemp
2022-10-07 20:13:38.325 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.const
2022-10-07 20:13:38.326 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.danfoss
2022-10-07 20:13:38.328 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.danfoss.thermostat
2022-10-07 20:13:38.335 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.develco
2022-10-07 20:13:38.342 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.develco.air_quality
2022-10-07 20:13:38.346 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.develco.heat_alarm
2022-10-07 20:13:38.348 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.develco.open_close
2022-10-07 20:13:38.351 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.develco.smoke_alarm
2022-10-07 20:13:38.353 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.echostar
2022-10-07 20:13:38.354 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.echostar.bell
2022-10-07 20:13:38.356 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ecolink
2022-10-07 20:13:38.358 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ecolink.contact
2022-10-07 20:13:38.362 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.edpwithus
2022-10-07 20:13:38.370 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.edpwithus.redy_plug
2022-10-07 20:13:38.371 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.elko
2022-10-07 20:13:38.384 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.elko.smart_super_thermostat
2022-10-07 20:13:38.390 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.eurotronic
2022-10-07 20:13:38.395 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.eurotronic.spzb0001
2022-10-07 20:13:38.397 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.gledopto
2022-10-07 20:13:38.399 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.gledopto.gls007z
2022-10-07 20:13:38.401 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.gledopto.soposhgu10
2022-10-07 20:13:38.402 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.heiman
2022-10-07 20:13:38.404 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.heiman.smoke
2022-10-07 20:13:38.407 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.hivehome
2022-10-07 20:13:38.409 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.hivehome.mot003V0
2022-10-07 20:13:38.411 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.hivehome.mot003V6
2022-10-07 20:13:38.413 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea
2022-10-07 20:13:38.428 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.blinds
2022-10-07 20:13:38.430 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.cctlightzha
2022-10-07 20:13:38.432 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.dimmer
2022-10-07 20:13:38.433 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.fivebtnremote
2022-10-07 20:13:38.435 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.fivebtnremotezha
2022-10-07 20:13:38.437 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.fourbtnremote
2022-10-07 20:13:38.439 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.motion
2022-10-07 20:13:38.440 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.motionzha
2022-10-07 20:13:38.442 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.opencloseremote
2022-10-07 20:13:38.445 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.shortcutbtn
2022-10-07 20:13:38.447 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.starkvind
2022-10-07 20:13:38.449 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.symfonisk
2022-10-07 20:13:38.452 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.tradfriplug
2022-10-07 20:13:38.453 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ikea.twobtnremote
2022-10-07 20:13:38.456 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.iluminize
2022-10-07 20:13:38.458 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.iluminize.cct
2022-10-07 20:13:38.463 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.iluminize.dim
2022-10-07 20:13:38.467 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.imagic
2022-10-07 20:13:38.469 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.imagic.gs1117s
2022-10-07 20:13:38.471 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.imagic.im1116s
2022-10-07 20:13:38.473 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.innr
2022-10-07 20:13:38.474 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.innr.innr_sp120_plug
2022-10-07 20:13:38.489 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.innr.innr_sp234_plug
2022-10-07 20:13:38.496 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.innr.rs228t
2022-10-07 20:13:38.498 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.inovelli
2022-10-07 20:13:38.505 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.inovelli.VZM31SN
2022-10-07 20:13:38.508 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.keenhome
2022-10-07 20:13:38.509 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.keenhome.sv02612mp13
2022-10-07 20:13:38.511 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.keenhome.weather
2022-10-07 20:13:38.535 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.kof
2022-10-07 20:13:38.537 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.kof.kof_mr101z
2022-10-07 20:13:38.544 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.konke
2022-10-07 20:13:38.548 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.konke.button
2022-10-07 20:13:38.550 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.konke.magnet
2022-10-07 20:13:38.551 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.konke.motion
2022-10-07 20:13:38.553 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.konke.temp
2022-10-07 20:13:38.555 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lds
2022-10-07 20:13:38.557 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lds.cctswitch
2022-10-07 20:13:38.559 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ledvance
2022-10-07 20:13:38.562 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ledvance.a19rgbw
2022-10-07 20:13:38.564 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.ledvance.flexrgbw
2022-10-07 20:13:38.565 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.legrand
2022-10-07 20:13:38.567 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.legrand.dimmer
2022-10-07 20:13:38.570 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lidl
2022-10-07 20:13:38.572 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lidl.TS0501A
2022-10-07 20:13:38.574 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lidl.cct
2022-10-07 20:13:38.581 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lidl.rgbcct
2022-10-07 20:13:38.585 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lidl.ts011f_plug
2022-10-07 20:13:38.653 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.linkind
2022-10-07 20:13:38.656 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.linkind.a001082
2022-10-07 20:13:38.658 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lixee
2022-10-07 20:13:38.660 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lixee.zlinky
2022-10-07 20:13:38.668 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lutron
2022-10-07 20:13:38.670 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.lutron.lzl4bwhl01remote
2022-10-07 20:13:38.674 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.mli
2022-10-07 20:13:38.675 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.mli.tint
2022-10-07 20:13:38.679 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.netvox
2022-10-07 20:13:38.681 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.netvox.z308e3ed
2022-10-07 20:13:38.685 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.nue
2022-10-07 20:13:38.687 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.nue.auwz02000
2022-10-07 20:13:38.689 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.orvibo
2022-10-07 20:13:38.692 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.orvibo.dimmer
2022-10-07 20:13:38.694 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.orvibo.motion
2022-10-07 20:13:38.696 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram
2022-10-07 20:13:38.697 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.a19rgbw
2022-10-07 20:13:38.699 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.cla60tw
2022-10-07 20:13:38.700 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.flexrgbw
2022-10-07 20:13:38.702 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.gardenpolesrgbw
2022-10-07 20:13:38.703 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.lightifyx4
2022-10-07 20:13:38.713 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.osramplug
2022-10-07 20:13:38.715 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.smartplusac05347
2022-10-07 20:13:38.716 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.switchmini
2022-10-07 20:13:38.718 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.osram.tunablewhite
2022-10-07 20:13:38.722 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.paulmann
2022-10-07 20:13:38.724 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.paulmann.fourbtnremote
2022-10-07 20:13:38.726 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philio
2022-10-07 20:13:38.729 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philio.pst03a
2022-10-07 20:13:38.731 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philips
2022-10-07 20:13:38.736 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philips.motion
2022-10-07 20:13:38.739 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philips.rdm001
2022-10-07 20:13:38.743 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philips.rom001
2022-10-07 20:13:38.745 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philips.rwl022
2022-10-07 20:13:38.747 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.philips.rwlfirstgen
2022-10-07 20:13:38.749 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.plaid
2022-10-07 20:13:38.750 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.plaid.soil
2022-10-07 20:13:38.754 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.salus
2022-10-07 20:13:38.756 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.salus.sp600
2022-10-07 20:13:38.761 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.samjin
2022-10-07 20:13:38.763 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.samjin.button
2022-10-07 20:13:38.765 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.samjin.button2
2022-10-07 20:13:38.766 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.samjin.multi
2022-10-07 20:13:38.768 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.samjin.multi2
2022-10-07 20:13:38.772 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sengled
2022-10-07 20:13:38.774 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sengled.e1e_g7f
2022-10-07 20:13:38.779 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sercomm
2022-10-07 20:13:38.781 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sercomm.szwtd02n
2022-10-07 20:13:38.785 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.siglis
2022-10-07 20:13:38.787 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.siglis.zigfred
2022-10-07 20:13:38.790 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sinope
2022-10-07 20:13:38.793 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sinope.light
2022-10-07 20:13:38.795 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sinope.sensor
2022-10-07 20:13:38.797 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sinope.switch
2022-10-07 20:13:38.806 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sinope.thermostat
2022-10-07 20:13:38.811 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings
2022-10-07 20:13:38.814 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.moisturev4
2022-10-07 20:13:38.817 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.motion
2022-10-07 20:13:38.819 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.multi
2022-10-07 20:13:38.821 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.multiv4
2022-10-07 20:13:38.822 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.pgc313
2022-10-07 20:13:38.825 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.pgc314
2022-10-07 20:13:38.827 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.smartthings.tag_v4
2022-10-07 20:13:38.832 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sonoff
2022-10-07 20:13:38.834 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sonoff.button
2022-10-07 20:13:38.837 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sourcingandcreation
2022-10-07 20:13:38.838 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.sourcingandcreation.smart_button
2022-10-07 20:13:38.840 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.terncy
2022-10-07 20:13:38.847 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.terncy.pp01
2022-10-07 20:13:38.849 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.terncy.sd01
2022-10-07 20:13:38.851 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.thirdreality
2022-10-07 20:13:38.853 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.thirdreality.button
2022-10-07 20:13:38.857 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.thirdreality.switch
2022-10-07 20:13:38.862 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.trust
2022-10-07 20:13:38.866 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.trust.zpir8000
2022-10-07 20:13:38.868 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya
2022-10-07 20:13:38.870 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.air
2022-10-07 20:13:38.874 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.air.ts0601_air_quality
2022-10-07 20:13:38.876 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.mcu
2022-10-07 20:13:38.877 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts000x
2022-10-07 20:13:38.879 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts001x
2022-10-07 20:13:38.881 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0041
2022-10-07 20:13:38.883 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0042
2022-10-07 20:13:38.885 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0043
2022-10-07 20:13:38.887 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0044
2022-10-07 20:13:38.889 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts004f
2022-10-07 20:13:38.891 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts011f_plug
2022-10-07 20:13:38.892 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts011f_switch
2022-10-07 20:13:38.894 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0121_plug
2022-10-07 20:13:38.896 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0201
2022-10-07 20:13:38.900 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0210
2022-10-07 20:13:38.902 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0211
2022-10-07 20:13:38.905 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0501b
2022-10-07 20:13:38.909 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0501bs
2022-10-07 20:13:38.911 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_co
2022-10-07 20:13:38.912 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_cover
2022-10-07 20:13:38.915 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_dimmer
2022-10-07 20:13:38.918 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_din_power
2022-10-07 20:13:38.932 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_electric_heating
2022-10-07 20:13:38.937 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_gas
2022-10-07 20:13:38.942 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_haozee
2022-10-07 20:13:38.949 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_motion
2022-10-07 20:13:38.959 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_sensor
2022-10-07 20:13:38.963 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_siren
2022-10-07 20:13:38.971 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_smoke
2022-10-07 20:13:38.974 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_switch
2022-10-07 20:13:38.977 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_trv
2022-10-07 20:13:39.004 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_trv_sas
2022-10-07 20:13:39.012 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts0601_valve
2022-10-07 20:13:39.021 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts110e
2022-10-07 20:13:39.025 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.tuya.ts130f
2022-10-07 20:13:39.030 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.visonic
2022-10-07 20:13:39.032 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.visonic.mct340e
2022-10-07 20:13:39.037 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.waxman
2022-10-07 20:13:39.039 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.waxman.leaksmart
2022-10-07 20:13:39.043 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xbee
2022-10-07 20:13:39.055 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xbee.xbee3_io
2022-10-07 20:13:39.057 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xbee.xbee_io
2022-10-07 20:13:39.059 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi
2022-10-07 20:13:39.060 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara
2022-10-07 20:13:39.063 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.ctrl_ln
2022-10-07 20:13:39.067 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.ctrl_neutral
2022-10-07 20:13:39.070 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.cube
2022-10-07 20:13:39.074 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.cube_aqgl01
2022-10-07 20:13:39.078 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.illumination
2022-10-07 20:13:39.080 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.light_aqcn2
2022-10-07 20:13:39.085 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.magnet_acn001
2022-10-07 20:13:39.086 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.magnet_aq2
2022-10-07 20:13:39.088 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.motion_ac01
2022-10-07 20:13:39.091 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.motion_ac02
2022-10-07 20:13:39.095 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.motion_agl02
2022-10-07 20:13:39.097 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.motion_aq2
2022-10-07 20:13:39.099 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.motion_aq2b
2022-10-07 20:13:39.100 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.opple_remote
2022-10-07 20:13:39.114 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.opple_switch
2022-10-07 20:13:39.120 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.plug
2022-10-07 20:13:39.122 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.plug_maus01
2022-10-07 20:13:39.124 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.plug_mmeu01
2022-10-07 20:13:39.126 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.relay_c2acn01
2022-10-07 20:13:39.128 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.remote_b186acn01
2022-10-07 20:13:39.130 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.remote_b286acn01
2022-10-07 20:13:39.132 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.remote_h1
2022-10-07 20:13:39.137 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.roller_curtain_e1
2022-10-07 20:13:39.144 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.sensor_swit
2022-10-07 20:13:39.147 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.sensor_switch_aq3
2022-10-07 20:13:39.149 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.switch_aq2
2022-10-07 20:13:39.151 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.tvoc
2022-10-07 20:13:39.154 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.vibration_aq1
2022-10-07 20:13:39.159 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.weather
2022-10-07 20:13:39.161 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.aqara.wleak_aq1
2022-10-07 20:13:39.163 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.mija
2022-10-07 20:13:39.165 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.mija.motion
2022-10-07 20:13:39.167 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.mija.sensor_ht
2022-10-07 20:13:39.168 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.mija.sensor_magnet
2022-10-07 20:13:39.170 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.mija.sensor_switch
2022-10-07 20:13:39.173 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.xiaomi.mija.smoke
2022-10-07 20:13:39.176 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.yale
2022-10-07 20:13:39.177 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.yale.realliving
2022-10-07 20:13:39.179 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.yooksmart
2022-10-07 20:13:39.181 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.yooksmart.D10110blinds
2022-10-07 20:13:39.184 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.zen
2022-10-07 20:13:39.189 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.zen.thermostat
2022-10-07 20:13:39.190 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.zhongxing
2022-10-07 20:13:39.194 DEBUG (MainThread) [zhaquirks] Loading quirks module zhaquirks.zhongxing.motion
2022-10-07 20:13:39.195 DEBUG (MainThread) [homeassistant.components.zha] ZHA storage file does not exist or was already removed
2022-10-07 20:13:46.411 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:13:46.411 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:13:46.412 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:13:46.412 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:13:46.412 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:13:46.413 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:13:46.413 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:13:46.414 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:13:46.414 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:13:46.414 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:13:46.415 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:13:46.415 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:13:46.415 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:13:46.415 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:13:46.416 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:13:46.417 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:13:46.417 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:13:46.417 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:13:46.417 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:13:46.418 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:13:46.418 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:13:46.418 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:13:46.419 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:13:46.419 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:13:46.419 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:13:46.419 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:13:46.420 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:13:46.420 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:13:46.420 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:13:46.420 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:13:46.421 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:13:46.421 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:13:46.421 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:13:46.421 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:13:46.422 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:13:46.422 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:13:46.423 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2022-10-07 20:13:46.423 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:04:4a:b1:3c: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2022-10-07 20:13:46.424 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.wleak_aq1.LeakAQ1'>
2022-10-07 20:13:46.424 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:04:a0:71:09: <class 'zhaquirks.xiaomi.aqara.wleak_aq1.LeakAQ1'>
2022-10-07 20:13:46.425 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote3'>
2022-10-07 20:13:46.425 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote2'>
2022-10-07 20:13:46.425 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote1'>
2022-10-07 20:13:46.425 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 0c:43:14:ff:fe:51:ec:a8: <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote1'>
2022-10-07 20:13:47.658 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.zha_ikea_five_button_remote, old_state=None, new_state=<state automation.zha_ikea_five_button_remote=off; last_triggered=2022-02-27T17:42:06.417280+01:00, mode=restart, current=0, id=1644667139811, friendly_name=ZHA - IKEA five button remote @ 2022-10-07T20:13:47.658854+02:00>>
2022-10-07 20:13:51.147 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_last_restart_time, old_state=None, new_state=<state sensor.zha_bridge_last_restart_time=unavailable; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:51.147264+02:00>>
2022-10-07 20:13:51.170 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_last_restart_time, old_state=<state sensor.zha_bridge_last_restart_time=unavailable; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:51.147264+02:00>, new_state=<state sensor.zha_bridge_last_restart_time=unknown; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:51.170421+02:00>>
2022-10-07 20:13:55.174 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:13:55.174 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:13:55.174 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:13:55.174 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:13:55.175 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:13:55.175 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:13:55.175 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:13:55.176 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:13:55.176 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:13:57.406 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_last_restart_time, old_state=<state sensor.zha_bridge_last_restart_time=unknown; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:51.170421+02:00>, new_state=<state sensor.zha_bridge_last_restart_time=unknown; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:57.406553+02:00>>
2022-10-07 20:13:57.410 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=None, new_state=<state sensor.zha_bridge_rssi=unavailable; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:57.410440+02:00>>
2022-10-07 20:13:57.412 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=<state sensor.zha_bridge_rssi=unavailable; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:57.410440+02:00>, new_state=<state sensor.zha_bridge_rssi=unknown; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:57.412081+02:00>>
2022-10-07 20:13:57.417 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=None, new_state=<state sensor.zha_bridge_signal=unavailable; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:57.416867+02:00>>
2022-10-07 20:13:57.418 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=<state sensor.zha_bridge_signal=unavailable; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:57.416867+02:00>, new_state=<state sensor.zha_bridge_signal=unknown; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:57.418244+02:00>>
2022-10-07 20:13:57.421 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_last_restart_time, old_state=<state sensor.zha_bridge_last_restart_time=unknown; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:57.406553+02:00>, new_state=<state sensor.zha_bridge_last_restart_time=unknown; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:57.421529+02:00>>
2022-10-07 20:13:57.422 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=<state sensor.zha_bridge_rssi=unknown; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:57.412081+02:00>, new_state=<state sensor.zha_bridge_rssi=unknown; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:57.422715+02:00>>
2022-10-07 20:13:57.423 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=<state sensor.zha_bridge_signal=unknown; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:57.418244+02:00>, new_state=<state sensor.zha_bridge_signal=unknown; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:57.423738+02:00>>
2022-10-07 20:13:58.042 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 1 of 3)
File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 172, in async_initialize
2022-10-07 20:13:58.136 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_last_restart_time, old_state=<state sensor.zha_bridge_last_restart_time=unknown; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:57.421529+02:00>, new_state=<state sensor.zha_bridge_last_restart_time=2022-10-06T19:22:17+00:00; device_class=timestamp, friendly_name=Tasmota Last Restart Time @ 2022-10-07T20:13:58.136247+02:00>>
2022-10-07 20:13:58.137 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=<state sensor.zha_bridge_rssi=unknown; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:57.422715+02:00>, new_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:58.137325+02:00>>
2022-10-07 20:13:58.138 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=<state sensor.zha_bridge_signal=unknown; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:57.423738+02:00>, new_state=<state sensor.zha_bridge_signal=-25; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:58.138140+02:00>>
2022-10-07 20:13:58.599 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:58.137325+02:00>, new_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:58.599474+02:00>>
2022-10-07 20:13:58.600 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=<state sensor.zha_bridge_signal=-25; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:58.138140+02:00>, new_state=<state sensor.zha_bridge_signal=-25; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:58.600633+02:00>>
2022-10-07 20:14:01.086 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:01.086 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:01.086 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:01.087 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:01.087 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:01.087 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:01.087 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:01.088 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:01.088 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:01.088 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:01.088 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:01.089 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:01.089 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:01.089 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:01.089 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:01.090 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:01.090 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:01.090 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:01.091 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:01.091 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:01.092 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:01.092 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:01.092 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:01.092 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:01.093 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:01.093 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:01.093 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:01.093 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:01.094 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:01.094 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:01.094 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:01.094 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:01.095 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:01.095 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:01.095 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:01.095 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:01.096 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2022-10-07 20:14:01.096 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:04:4a:b1:3c: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2022-10-07 20:14:01.097 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.wleak_aq1.LeakAQ1'>
2022-10-07 20:14:01.097 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:04:a0:71:09: <class 'zhaquirks.xiaomi.aqara.wleak_aq1.LeakAQ1'>
2022-10-07 20:14:01.098 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote3'>
2022-10-07 20:14:01.098 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote2'>
2022-10-07 20:14:01.098 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote1'>
2022-10-07 20:14:01.099 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 0c:43:14:ff:fe:51:ec:a8: <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote1'>
2022-10-07 20:14:07.118 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:07.119 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:07.119 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:07.120 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:07.121 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:07.122 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:07.122 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:07.123 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:07.123 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:09.110 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 2 of 3)
File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 172, in async_initialize
2022-10-07 20:14:12.212 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:12.212 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:12.213 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:12.213 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:12.214 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:12.214 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:12.215 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:12.215 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:12.216 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:12.217 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:12.217 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:12.218 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:12.218 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:12.219 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:12.219 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:12.220 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:12.220 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:12.221 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:12.221 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:12.222 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:12.222 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:12.223 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:12.223 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:12.224 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:12.224 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:12.225 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:12.225 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:12.226 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:12.226 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:12.227 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:12.227 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:12.228 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:12.228 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:12.229 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:12.229 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:12.230 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:12.231 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2022-10-07 20:14:12.231 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:04:4a:b1:3c: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2022-10-07 20:14:12.233 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.wleak_aq1.LeakAQ1'>
2022-10-07 20:14:12.234 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:04:a0:71:09: <class 'zhaquirks.xiaomi.aqara.wleak_aq1.LeakAQ1'>
2022-10-07 20:14:12.235 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote3'>
2022-10-07 20:14:12.236 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote2'>
2022-10-07 20:14:12.236 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote1'>
2022-10-07 20:14:12.236 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 0c:43:14:ff:fe:51:ec:a8: <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote1'>
2022-10-07 20:14:18.190 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2022-10-07 20:14:18.190 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2022-10-07 20:14:18.191 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-10-07 20:14:18.191 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-10-07 20:14:18.192 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2022-10-07 20:14:18.192 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-10-07 20:14:18.193 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-10-07 20:14:18.193 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-10-07 20:14:18.194 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-10-07 20:14:20.167 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 3 of 3)
File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 172, in async_initialize
2022-10-07 20:14:20.194 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry socket://192.168.2.166:8888 for zha
File "/usr/src/homeassistant/homeassistant/components/zha/__init__.py", line 112, in async_setup_entry
await zha_gateway.async_initialize()
File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 185, in async_initialize
File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 172, in async_initialize
2022-10-07 20:14:20.203 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=zha>
2022-10-07 20:17:27.502 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:13:58.599474+02:00>, new_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:17:27.502542+02:00>>
2022-10-07 20:17:27.504 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=<state sensor.zha_bridge_signal=-25; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:13:58.600633+02:00>, new_state=<state sensor.zha_bridge_signal=-26; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:17:27.504764+02:00>>
2022-10-07 20:18:52.769 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_state, old_state=<state sensor.zha_bridge_state=unknown; icon=mdi:router-wireless, friendly_name=ZHA Bridge State @ 2022-10-07T20:13:29.025403+02:00>, new_state=<state sensor.zha_bridge_state=online; icon=mdi:router-wireless, friendly_name=ZHA Bridge State @ 2022-10-07T20:18:52.769800+02:00>>
2022-10-07 20:22:27.512 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_rssi, old_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:17:27.502542+02:00>, new_state=<state sensor.zha_bridge_rssi=100; state_class=measurement, unit_of_measurement=%, icon=mdi:access-point, friendly_name=Tasmota RSSI @ 2022-10-07T20:22:27.511743+02:00>>
2022-10-07 20:22:27.514 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zha_bridge_signal, old_state=<state sensor.zha_bridge_signal=-26; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:17:27.504764+02:00>, new_state=<state sensor.zha_bridge_signal=-26; state_class=measurement, unit_of_measurement=dBm, device_class=signal_strength, friendly_name=Tasmota Signal @ 2022-10-07T20:22:27.514273+02:00>>
blackscreener commented 1 year ago

EZSP log:

2022-10-07 20:13:47.242 DEBUG (MainThread) [zigpy.backups] Adding a new backup NetworkBackup(backup_time=datetime.datetime(2022, 10, 6, 19, 58, 44, 900978, tzinfo=datetime.timezone.utc), network_info=NetworkInfo(extended_pan_id=cc:cc:cc:cc:06:96:2b:c8, pan_id=0x2BC8, 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:9d:46:c8:2b:96:06:a1:40:9d:46:c8:2b:96:06, tx_counter=1781764, 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=40960, rx_counter=0, seq=0, partner_ieee=60:a4:23:ff:fe:42:55:82), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_write_custom_eui64': True}}, source='bellows@0.33.1'), node_info=NodeInfo(nwk=0x0000, ieee=60:a4:23:ff:fe:42:55:82, logical_type=<LogicalType.Coordinator: 0>))
2022-10-07 20:13:48.520 DEBUG (MainThread) [bellows.ezsp] Resetting EZSP
2022-10-07 20:13:50.045 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (4,)
2022-10-07 20:13:50.259 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 0 (version) received: b'08029067'
2022-10-07 20:13:50.289 DEBUG (MainThread) [bellows.ezsp] Switching to EZSP protocol version 8
2022-10-07 20:13:50.291 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (8,)
2022-10-07 20:13:50.518 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 0 (version) received: b'08029067'
2022-10-07 20:13:50.520 DEBUG (MainThread) [bellows.ezsp] EZSP Stack Type: 2, Stack Version: 6790, Protocol version: 8
2022-10-07 20:13:50.520 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:13:50.574 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'000138'
2022-10-07 20:13:50.590 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:13:50.634 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'004000'
2022-10-07 20:13:50.635 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers before any configurations: 56/64
2022-10-07 20:13:50.635 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>, 2)
2022-10-07 20:13:50.684 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:50.688 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>, <EmberZdoConfigurationFlags.APP_HANDLES_UNSUPPORTED_ZDO_REQUESTS|APP_RECEIVES_SUPPORTED_ZDO_REQUESTS: 3>)
2022-10-07 20:13:50.734 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:50.745 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>, 5)
2022-10-07 20:13:50.795 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:50.798 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>, 2)
2022-10-07 20:13:50.839 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:50.841 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>, 8)
2022-10-07 20:13:50.900 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:50.901 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>, 16)
2022-10-07 20:13:50.980 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.096 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>, 2)
2022-10-07 20:13:51.172 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.177 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>, 7680)
2022-10-07 20:13:51.207 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.209 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>, 90)
2022-10-07 20:13:51.253 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.255 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>, 16)
2022-10-07 20:13:51.328 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.336 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>, 16)
2022-10-07 20:13:51.405 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.422 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SUPPORTED_NETWORKS: 45>, 1)
2022-10-07 20:13:51.465 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.476 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:13:51.530 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'000138'
2022-10-07 20:13:51.531 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:13:51.582 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'004000'
2022-10-07 20:13:51.600 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers before all memory allocation: 56/64
2022-10-07 20:13:51.600 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>, 255)
2022-10-07 20:13:51.654 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:13:51.696 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:13:51.773 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0001f5'
2022-10-07 20:13:51.794 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:13:51.862 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'00fd00'
2022-10-07 20:13:51.864 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers after all memory allocation: 245/253
2022-10-07 20:13:51.865 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_APS_UNICAST_MESSAGE_COUNT: 3>,)
2022-10-07 20:13:51.906 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000a00'
2022-10-07 20:13:51.923 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])
2022-10-07 20:13:51.975 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 2 (addEndpoint) received: b'00'
2022-10-07 20:13:51.996 DEBUG (MainThread) [bellows.zigbee.application] Ezsp adding endpoint: [<EzspStatus.SUCCESS: 0>]
2022-10-07 20:13:51.996 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addEndpoint: (2, 49246, <DeviceType.CONTROLLER: 2080>, 0, 1, 0, [0], [])
2022-10-07 20:13:52.064 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 2 (addEndpoint) received: b'00'
2022-10-07 20:13:52.067 DEBUG (MainThread) [bellows.zigbee.application] Ezsp adding endpoint: [<EzspStatus.SUCCESS: 0>]
2022-10-07 20:13:52.067 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:13:52.137 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:13:52.144 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'
2022-10-07 20:13:52.144 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:13:52.181 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:13:52.185 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'
2022-10-07 20:13:52.185 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:13:52.234 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:13:52.236 INFO (MainThread) [bellows.zigbee.application] EZSP Radio manufacturer:
2022-10-07 20:13:52.236 INFO (MainThread) [bellows.zigbee.application] EZSP Radio board name:
2022-10-07 20:13:52.236 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:13:52.295 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'00'
2022-10-07 20:13:52.298 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkInit: ()
2022-10-07 20:13:52.347 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 23 (networkInit) received: b'00'
2022-10-07 20:13:52.350 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 25 (stackStatusHandler) received: b'90'
2022-10-07 20:13:52.354 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2022-10-07 20:13:52.406 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 40 (getNetworkParameters) received: b'0001c82b9606ccccccccc82b140b0000000000f8ff07'
2022-10-07 20:13:52.411 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2022-10-07 20:13:52.456 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 39 (getNodeId) received: b'0000'
2022-10-07 20:13:52.458 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2022-10-07 20:13:52.503 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 38 (getEui64) received: b'825542feff23a460'
2022-10-07 20:13:52.507 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2022-10-07 20:13:52.553 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000500'
2022-10-07 20:13:52.581 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2022-10-07 20:13:52.629 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'00030003a1409d46c82b9606a1409d46c82b960601801b0000000000000000000000000000'
2022-10-07 20:13:52.636 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2022-10-07 20:13:52.718 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'001a00015a6967426565416c6c69616e6365303900a000000000000000825542feff23a460'
2022-10-07 20:13:52.735 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2022-10-07 20:13:52.775 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:13:52.779 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:13:52.836 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:13:52.853 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'
2022-10-07 20:13:52.854 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:13:52.924 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:13:52.941 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'
2022-10-07 20:13:52.942 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:13:52.977 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:13:52.997 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2022-10-07 20:13:53.037 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'08ffffffffffffffff'
2022-10-07 20:13:53.079 DEBUG (MainThread) [zigpy.application] Network info: NetworkInfo(extended_pan_id=cc:cc:cc:cc:06:96:2b:c8, pan_id=0x2BC8, 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:9d:46:c8:2b:96:06:a1:40:9d:46:c8:2b:96:06, tx_counter=1802241, 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=40960, rx_counter=0, seq=0, partner_ieee=60:a4:23:ff:fe:42:55:82), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_write_custom_eui64': True}}, source='bellows@0.34.1')
2022-10-07 20:13:53.083 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:13:53.160 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'02'
2022-10-07 20:13:53.163 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TC_KEY_REQUEST_POLICY: 5>, <EzspDecisionId.ALLOW_TC_KEY_REQUESTS: 81>)
2022-10-07 20:13:53.185 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:13:53.186 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.IGNORE_UNSECURED_REJOINS|ALLOW_JOINS: 9>)
2022-10-07 20:13:53.246 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:13:53.263 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.APP_KEY_REQUEST_POLICY: 6>, <EzspDecisionId.DENY_APP_KEY_REQUESTS: 96>)
2022-10-07 20:13:53.368 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:13:53.379 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:13:53.398 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'02'
2022-10-07 20:13:53.405 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2022-10-07 20:13:53.468 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 40 (getNetworkParameters) received: b'0001c82b9606ccccccccc82b140b0000000000f8ff07'
2022-10-07 20:13:53.480 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2022-10-07 20:13:53.507 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 39 (getNodeId) received: b'0000'
2022-10-07 20:13:53.526 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2022-10-07 20:13:53.568 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 38 (getEui64) received: b'825542feff23a460'
2022-10-07 20:13:53.575 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2022-10-07 20:13:53.616 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000500'
2022-10-07 20:13:53.634 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2022-10-07 20:13:53.680 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'00030003a1409d46c82b9606a1409d46c82b960601801b0000000000000000000000000000'
2022-10-07 20:13:53.686 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2022-10-07 20:13:53.733 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'001a00015a6967426565416c6c69616e6365303900a000000000000000825542feff23a460'
2022-10-07 20:13:53.737 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2022-10-07 20:13:54.484 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:13:54.485 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:13:54.529 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:13:54.602 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:13:54.629 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'
2022-10-07 20:13:54.629 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:13:54.694 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:13:54.729 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'
2022-10-07 20:13:54.729 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:13:54.802 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:13:54.813 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2022-10-07 20:13:54.854 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'08ffffffffffffffff'
2022-10-07 20:13:54.889 DEBUG (MainThread) [bellows.zigbee.application] Starting EZSP watchdog
2022-10-07 20:13:54.894 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (60:a4:23:ff:fe:42:55:82, True)
2022-10-07 20:13:54.960 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 126 (setExtendedTimeout) received: b''
2022-10-07 20:13:54.981 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0000, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=1), 2, b'\x01\x00\x00')
2022-10-07 20:13:55.013 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'00be'
2022-10-07 20:13:55.016 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0000000200000040010000beff000000ffff03010000'
2022-10-07 20:13:55.019 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x01\x00\x00'
2022-10-07 20:13:55.043 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0000000280000040010000bfff000000ffff110100000000408fcdab528000412c800000'
2022-10-07 20:13:55.047 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'\x01\x00\x00\x00\x00@\x8f\xcd\xabR\x80\x00A,\x80\x00\x00'
2022-10-07 20:13:55.050 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00000000000200000040010000be020000'
2022-10-07 20:13:55.083 INFO (MainThread) [zigpy.device] [0x0000] Already have endpoints: {0: <bellows.zigbee.device.EZSPZDOEndpoint object at 0x7fa0285d8610>, 1: <EZSPEndpoint id=1 in=[] out=[] status=<Status.NEW: 0>>}
2022-10-07 20:13:55.083 INFO (MainThread) [zigpy.device] [0x0000] Initializing endpoints [<EZSPEndpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
2022-10-07 20:13:55.086 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0000, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=3), 4, b'\x03\x00\x00\x01')
2022-10-07 20:13:55.117 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'00c0'
2022-10-07 20:13:55.123 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0000000400000040010000c0ff000000ffff0403000001'
2022-10-07 20:13:55.126 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 4: b'\x03\x00\x00\x01'
2022-10-07 20:13:55.133 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0000000480000040010000c1ff000000ffff1f030000001a01040100040005000006000a0019000105040100200000050205'
2022-10-07 20:13:55.138 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'\x03\x00\x00\x00\x1a\x01\x04\x01\x00\x04\x00\x05\x00\x00\x06\x00\n\x00\x19\x00\x01\x05\x04\x01\x00 \x00\x00\x05\x02\x05'
2022-10-07 20:13:55.139 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00000000000400000040010000c0040000'
2022-10-07 20:13:55.172 INFO (MainThread) [zigpy.device] [0x0000] Discovered basic device information for <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=True>
2022-10-07 20:13:55.172 DEBUG (MainThread) [zigpy.application] Device is initialized <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=True>
2022-10-07 20:13:55.174 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for Silicon Labs EZSP (60:a4:23:ff:fe:42:55:82)
2022-10-07 20:13:55.178 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>,)
2022-10-07 20:13:55.240 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'001000'
2022-10-07 20:13:55.272 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (0,)
2022-10-07 20:13:55.333 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.338 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (1,)
2022-10-07 20:13:55.422 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.432 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (2,)
2022-10-07 20:13:55.497 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.502 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (3,)
2022-10-07 20:13:55.554 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.557 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (4,)
2022-10-07 20:13:55.588 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.590 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (5,)
2022-10-07 20:13:55.665 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.725 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (6,)
2022-10-07 20:13:55.794 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.827 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (7,)
2022-10-07 20:13:55.884 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.895 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (8,)
2022-10-07 20:13:55.965 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:55.968 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (9,)
2022-10-07 20:13:56.051 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.057 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (10,)
2022-10-07 20:13:56.121 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.151 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (11,)
2022-10-07 20:13:56.330 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.372 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (12,)
2022-10-07 20:13:56.536 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.545 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (13,)
2022-10-07 20:13:56.614 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.629 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (14,)
2022-10-07 20:13:56.736 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.794 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (15,)
2022-10-07 20:13:56.855 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:13:56.863 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setMulticastTableEntry: (0, EmberMulticastTableEntry(multicastId=0x0000, endpoint=1, networkIndex=0))
2022-10-07 20:13:56.897 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 100 (setMulticastTableEntry) received: b'00'
2022-10-07 20:13:56.904 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (0xfffc, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=5), 0, 6, b'\x05\x00\x00')
2022-10-07 20:13:56.928 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57])
2022-10-07 20:13:56.943 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 54 (sendBroadcast) received: b'00c2'
2022-10-07 20:13:56.970 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0500003600000000010000c2ff000000ffff03050000'
2022-10-07 20:13:57.045 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 175 (addTransientLinkKey) received: b'00'
2022-10-07 20:13:57.053 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.ALLOW_UNSECURED_REJOINS|ALLOW_JOINS: 3>)
2022-10-07 20:13:57.109 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:13:57.978 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'06fcff00003600000000010000c2066600'
2022-10-07 20:13:58.042 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 1 of 3)
2022-10-07 20:13:59.130 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.IGNORE_UNSECURED_REJOINS|ALLOW_JOINS: 9>)
2022-10-07 20:14:01.154 DEBUG (MainThread) [zigpy.backups] Adding a new backup NetworkBackup(backup_time=datetime.datetime(2022, 10, 6, 19, 58, 44, 900978, tzinfo=datetime.timezone.utc), network_info=NetworkInfo(extended_pan_id=cc:cc:cc:cc:06:96:2b:c8, pan_id=0x2BC8, 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:9d:46:c8:2b:96:06:a1:40:9d:46:c8:2b:96:06, tx_counter=1781764, 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=40960, rx_counter=0, seq=0, partner_ieee=60:a4:23:ff:fe:42:55:82), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_write_custom_eui64': True}}, source='bellows@0.33.1'), node_info=NodeInfo(nwk=0x0000, ieee=60:a4:23:ff:fe:42:55:82, logical_type=<LogicalType.Coordinator: 0>))
2022-10-07 20:14:02.323 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 1 (getLibraryStatus) received: b'45000500001f0000000000000000ff000000ffff0a81013cb14a04008d1500'
2022-10-07 20:14:02.431 DEBUG (MainThread) [bellows.ezsp] Resetting EZSP
2022-10-07 20:14:03.587 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (4,)
2022-10-07 20:14:03.637 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 0 (version) received: b'08029067'
2022-10-07 20:14:03.638 DEBUG (MainThread) [bellows.ezsp] Switching to EZSP protocol version 8
2022-10-07 20:14:03.639 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (8,)
2022-10-07 20:14:03.691 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 0 (version) received: b'08029067'
2022-10-07 20:14:03.691 DEBUG (MainThread) [bellows.ezsp] EZSP Stack Type: 2, Stack Version: 6790, Protocol version: 8
2022-10-07 20:14:03.692 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:14:03.745 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'000138'
2022-10-07 20:14:03.747 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:14:03.799 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'004000'
2022-10-07 20:14:03.800 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers before any configurations: 56/64
2022-10-07 20:14:03.801 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>, 2)
2022-10-07 20:14:03.854 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:03.855 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>, <EmberZdoConfigurationFlags.APP_HANDLES_UNSUPPORTED_ZDO_REQUESTS|APP_RECEIVES_SUPPORTED_ZDO_REQUESTS: 3>)
2022-10-07 20:14:03.907 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:03.909 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>, 5)
2022-10-07 20:14:03.962 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:03.963 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>, 2)
2022-10-07 20:14:04.018 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.021 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>, 8)
2022-10-07 20:14:04.070 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.071 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>, 16)
2022-10-07 20:14:04.176 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.193 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>, 2)
File "/usr/local/lib/python3.10/site-packages/bellows/ezsp/v8/__init__.py", line 51, in pre_permit
2022-10-07 20:14:04.264 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.265 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>, 7680)
2022-10-07 20:14:04.317 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.317 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>, 90)
2022-10-07 20:14:04.371 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.372 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>, 16)
2022-10-07 20:14:04.445 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.445 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>, 16)
2022-10-07 20:14:04.515 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.515 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SUPPORTED_NETWORKS: 45>, 1)
2022-10-07 20:14:04.586 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.587 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:14:04.651 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'000138'
2022-10-07 20:14:04.651 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:14:04.694 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'004000'
2022-10-07 20:14:04.694 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers before all memory allocation: 56/64
2022-10-07 20:14:04.694 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>, 255)
2022-10-07 20:14:04.777 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:04.778 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:14:04.819 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0001f5'
2022-10-07 20:14:04.819 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:14:04.872 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'00fd00'
2022-10-07 20:14:04.873 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers after all memory allocation: 245/253
2022-10-07 20:14:04.874 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_APS_UNICAST_MESSAGE_COUNT: 3>,)
2022-10-07 20:14:04.925 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000a00'
2022-10-07 20:14:04.927 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])
2022-10-07 20:14:05.156 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 2 (addEndpoint) received: b'00'
2022-10-07 20:14:05.160 DEBUG (MainThread) [bellows.zigbee.application] Ezsp adding endpoint: [<EzspStatus.SUCCESS: 0>]
2022-10-07 20:14:05.160 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addEndpoint: (2, 49246, <DeviceType.CONTROLLER: 2080>, 0, 1, 0, [0], [])
2022-10-07 20:14:05.186 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 2 (addEndpoint) received: b'00'
2022-10-07 20:14:05.186 DEBUG (MainThread) [bellows.zigbee.application] Ezsp adding endpoint: [<EzspStatus.SUCCESS: 0>]
2022-10-07 20:14:05.186 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:14:05.241 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:05.242 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'
2022-10-07 20:14:05.242 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:14:05.295 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:05.295 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'
2022-10-07 20:14:05.296 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:14:05.349 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:14:05.350 INFO (MainThread) [bellows.zigbee.application] EZSP Radio manufacturer:
2022-10-07 20:14:05.350 INFO (MainThread) [bellows.zigbee.application] EZSP Radio board name:
2022-10-07 20:14:05.351 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:14:05.403 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'00'
2022-10-07 20:14:05.405 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkInit: ()
2022-10-07 20:14:05.458 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 23 (networkInit) received: b'00'
2022-10-07 20:14:05.459 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2022-10-07 20:14:05.463 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 25 (stackStatusHandler) received: b'90'
2022-10-07 20:14:05.515 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 40 (getNetworkParameters) received: b'0001c82b9606ccccccccc82b140b0000000000f8ff07'
2022-10-07 20:14:05.519 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2022-10-07 20:14:05.567 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 39 (getNodeId) received: b'0000'
2022-10-07 20:14:05.568 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2022-10-07 20:14:05.620 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 38 (getEui64) received: b'825542feff23a460'
2022-10-07 20:14:05.621 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2022-10-07 20:14:05.674 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000500'
2022-10-07 20:14:05.675 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2022-10-07 20:14:05.730 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'00030003a1409d46c82b9606a1409d46c82b960601901b0000000000000000000000000000'
2022-10-07 20:14:05.734 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2022-10-07 20:14:05.788 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'001a00015a6967426565416c6c69616e6365303900a000000000000000825542feff23a460'
2022-10-07 20:14:05.791 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2022-10-07 20:14:05.838 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:14:05.840 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:14:05.891 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:05.892 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'
2022-10-07 20:14:05.893 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:14:05.945 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:05.947 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'
2022-10-07 20:14:05.947 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:14:05.999 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:14:06.000 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2022-10-07 20:14:06.053 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'08ffffffffffffffff'
2022-10-07 20:14:06.055 DEBUG (MainThread) [zigpy.application] Network info: NetworkInfo(extended_pan_id=cc:cc:cc:cc:06:96:2b:c8, pan_id=0x2BC8, 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:9d:46:c8:2b:96:06:a1:40:9d:46:c8:2b:96:06, tx_counter=1806337, 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=40960, rx_counter=0, seq=0, partner_ieee=60:a4:23:ff:fe:42:55:82), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_write_custom_eui64': True}}, source='bellows@0.34.1')
2022-10-07 20:14:06.057 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:14:06.107 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'02'
2022-10-07 20:14:06.109 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TC_KEY_REQUEST_POLICY: 5>, <EzspDecisionId.ALLOW_TC_KEY_REQUESTS: 81>)
2022-10-07 20:14:06.161 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:06.163 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.IGNORE_UNSECURED_REJOINS|ALLOW_JOINS: 9>)
2022-10-07 20:14:06.214 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:06.214 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.APP_KEY_REQUEST_POLICY: 6>, <EzspDecisionId.DENY_APP_KEY_REQUESTS: 96>)
2022-10-07 20:14:06.269 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:06.271 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:14:06.322 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'02'
2022-10-07 20:14:06.323 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2022-10-07 20:14:06.380 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 40 (getNetworkParameters) received: b'0001c82b9606ccccccccc82b140b0000000000f8ff07'
2022-10-07 20:14:06.388 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2022-10-07 20:14:06.429 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 39 (getNodeId) received: b'0000'
2022-10-07 20:14:06.430 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2022-10-07 20:14:06.482 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 38 (getEui64) received: b'825542feff23a460'
2022-10-07 20:14:06.484 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2022-10-07 20:14:06.535 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000500'
2022-10-07 20:14:06.537 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2022-10-07 20:14:06.592 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'00030003a1409d46c82b9606a1409d46c82b960601901b0000000000000000000000000000'
2022-10-07 20:14:06.597 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2022-10-07 20:14:06.647 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'001a00015a6967426565416c6c69616e6365303900a000000000000000825542feff23a460'
2022-10-07 20:14:06.651 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2022-10-07 20:14:06.697 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:14:06.700 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:14:06.752 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:06.753 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'
2022-10-07 20:14:06.753 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:14:06.807 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:06.808 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'
2022-10-07 20:14:06.809 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:14:06.859 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:14:06.861 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2022-10-07 20:14:06.913 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'08ffffffffffffffff'
2022-10-07 20:14:06.915 DEBUG (MainThread) [bellows.zigbee.application] Starting EZSP watchdog
2022-10-07 20:14:06.919 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (60:a4:23:ff:fe:42:55:82, True)
2022-10-07 20:14:06.968 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 126 (setExtendedTimeout) received: b''
2022-10-07 20:14:06.970 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0000, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=1), 2, b'\x01\x00\x00')
2022-10-07 20:14:07.027 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0084'
2022-10-07 20:14:07.033 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000020000004001000084ff000000ffff03010000'
2022-10-07 20:14:07.038 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x01\x00\x00'
2022-10-07 20:14:07.043 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000085ff000000ffff110100000000408fcdab528000412c800000'
2022-10-07 20:14:07.049 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'\x01\x00\x00\x00\x00@\x8f\xcd\xabR\x80\x00A,\x80\x00\x00'
2022-10-07 20:14:07.051 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'0000000000020000004001000084020000'
2022-10-07 20:14:07.058 INFO (MainThread) [zigpy.device] [0x0000] Already have endpoints: {0: <bellows.zigbee.device.EZSPZDOEndpoint object at 0x7fa021963310>, 1: <EZSPEndpoint id=1 in=[] out=[] status=<Status.NEW: 0>>}
2022-10-07 20:14:07.059 INFO (MainThread) [zigpy.device] [0x0000] Initializing endpoints [<EZSPEndpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
2022-10-07 20:14:07.062 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0000, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=3), 4, b'\x03\x00\x00\x01')
2022-10-07 20:14:07.085 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0086'
2022-10-07 20:14:07.091 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000040000004001000086ff000000ffff0403000001'
2022-10-07 20:14:07.096 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 4: b'\x03\x00\x00\x01'
2022-10-07 20:14:07.102 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000048000004001000087ff000000ffff1f030000001a01040100040005000006000a0019000105040100200000050205'
2022-10-07 20:14:07.108 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'\x03\x00\x00\x00\x1a\x01\x04\x01\x00\x04\x00\x05\x00\x00\x06\x00\n\x00\x19\x00\x01\x05\x04\x01\x00 \x00\x00\x05\x02\x05'
2022-10-07 20:14:07.109 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'0000000000040000004001000086040000'
2022-10-07 20:14:07.117 INFO (MainThread) [zigpy.device] [0x0000] Discovered basic device information for <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=True>
2022-10-07 20:14:07.117 DEBUG (MainThread) [zigpy.application] Device is initialized <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=True>
2022-10-07 20:14:07.118 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for Silicon Labs EZSP (60:a4:23:ff:fe:42:55:82)
2022-10-07 20:14:07.124 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>,)
2022-10-07 20:14:07.143 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'001000'
2022-10-07 20:14:07.145 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (0,)
2022-10-07 20:14:07.194 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.197 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (1,)
2022-10-07 20:14:07.248 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.251 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (2,)
2022-10-07 20:14:07.301 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.304 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (3,)
2022-10-07 20:14:07.355 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.357 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (4,)
2022-10-07 20:14:07.409 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.411 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (5,)
2022-10-07 20:14:07.462 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.464 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (6,)
2022-10-07 20:14:07.516 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.519 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (7,)
2022-10-07 20:14:07.570 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.573 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (8,)
2022-10-07 20:14:07.622 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.625 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (9,)
2022-10-07 20:14:07.677 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.679 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (10,)
2022-10-07 20:14:07.730 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.732 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (11,)
2022-10-07 20:14:07.784 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.786 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (12,)
2022-10-07 20:14:07.837 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.839 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (13,)
2022-10-07 20:14:07.890 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.892 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (14,)
2022-10-07 20:14:07.945 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:07.947 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (15,)
2022-10-07 20:14:07.999 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:08.003 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setMulticastTableEntry: (0, EmberMulticastTableEntry(multicastId=0x0000, endpoint=1, networkIndex=0))
2022-10-07 20:14:08.052 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 100 (setMulticastTableEntry) received: b'00'
2022-10-07 20:14:08.056 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (0xfffc, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=5), 0, 6, b'\x05\x00\x00')
2022-10-07 20:14:08.062 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57])
2022-10-07 20:14:08.110 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 54 (sendBroadcast) received: b'0088'
2022-10-07 20:14:08.114 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'050000360000000001000088ff000000ffff03050000'
2022-10-07 20:14:08.123 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 175 (addTransientLinkKey) received: b'00'
2022-10-07 20:14:08.124 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.ALLOW_UNSECURED_REJOINS|ALLOW_JOINS: 3>)
2022-10-07 20:14:08.176 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:09.088 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'06fcff0000360000000001000088066600'
2022-10-07 20:14:09.110 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 2 of 3)
2022-10-07 20:14:10.179 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.IGNORE_UNSECURED_REJOINS|ALLOW_JOINS: 9>)
2022-10-07 20:14:12.303 DEBUG (MainThread) [zigpy.backups] Adding a new backup NetworkBackup(backup_time=datetime.datetime(2022, 10, 6, 19, 58, 44, 900978, tzinfo=datetime.timezone.utc), network_info=NetworkInfo(extended_pan_id=cc:cc:cc:cc:06:96:2b:c8, pan_id=0x2BC8, 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:9d:46:c8:2b:96:06:a1:40:9d:46:c8:2b:96:06, tx_counter=1781764, 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=40960, rx_counter=0, seq=0, partner_ieee=60:a4:23:ff:fe:42:55:82), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_write_custom_eui64': True}}, source='bellows@0.33.1'), node_info=NodeInfo(nwk=0x0000, ieee=60:a4:23:ff:fe:42:55:82, logical_type=<LogicalType.Coordinator: 0>))
2022-10-07 20:14:13.493 DEBUG (MainThread) [bellows.ezsp] Resetting EZSP
2022-10-07 20:14:14.858 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (4,)
2022-10-07 20:14:14.908 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 0 (version) received: b'08029067'
2022-10-07 20:14:14.909 DEBUG (MainThread) [bellows.ezsp] Switching to EZSP protocol version 8
2022-10-07 20:14:14.910 DEBUG (MainThread) [bellows.ezsp.protocol] Send command version: (8,)
2022-10-07 20:14:14.966 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 0 (version) received: b'08029067'
2022-10-07 20:14:14.968 DEBUG (MainThread) [bellows.ezsp] EZSP Stack Type: 2, Stack Version: 6790, Protocol version: 8
2022-10-07 20:14:14.968 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:14:15.015 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'000138'
2022-10-07 20:14:15.017 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:14:15.069 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'004000'
2022-10-07 20:14:15.070 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers before any configurations: 56/64
2022-10-07 20:14:15.071 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD: 34>, 2)
2022-10-07 20:14:15.123 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.124 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS: 42>, <EmberZdoConfigurationFlags.APP_HANDLES_UNSUPPORTED_ZDO_REQUESTS|APP_RECEIVES_SUPPORTED_ZDO_REQUESTS: 3>)
2022-10-07 20:14:15.177 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.179 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>, 5)
File "/usr/local/lib/python3.10/site-packages/bellows/ezsp/v8/__init__.py", line 51, in pre_permit
2022-10-07 20:14:15.231 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.232 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_STACK_PROFILE: 12>, 2)
2022-10-07 20:14:15.285 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.287 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT: 19>, 8)
2022-10-07 20:14:15.338 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.339 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>, 16)
2022-10-07 20:14:15.411 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.412 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE: 25>, 2)
2022-10-07 20:14:15.484 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.485 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT: 18>, 7680)
2022-10-07 20:14:15.537 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.538 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S: 56>, 90)
2022-10-07 20:14:15.591 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.593 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SOURCE_ROUTE_TABLE_SIZE: 26>, 16)
2022-10-07 20:14:15.663 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.665 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_ADDRESS_TABLE_SIZE: 5>, 16)
2022-10-07 20:14:15.736 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.737 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SUPPORTED_NETWORKS: 45>, 1)
2022-10-07 20:14:15.807 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.809 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:14:15.865 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'000138'
2022-10-07 20:14:15.867 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:14:15.916 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'004000'
2022-10-07 20:14:15.918 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers before all memory allocation: 56/64
2022-10-07 20:14:15.918 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>, 255)
2022-10-07 20:14:15.987 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
2022-10-07 20:14:15.988 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2022-10-07 20:14:16.041 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0001f5'
2022-10-07 20:14:16.043 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_PACKET_BUFFER_COUNT: 1>,)
2022-10-07 20:14:16.096 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'00fd00'
2022-10-07 20:14:16.097 DEBUG (MainThread) [bellows.ezsp.protocol] Free/configured buffers after all memory allocation: 245/253
2022-10-07 20:14:16.097 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_APS_UNICAST_MESSAGE_COUNT: 3>,)
2022-10-07 20:14:16.150 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000a00'
2022-10-07 20:14:16.153 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])
2022-10-07 20:14:16.206 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 2 (addEndpoint) received: b'00'
2022-10-07 20:14:16.207 DEBUG (MainThread) [bellows.zigbee.application] Ezsp adding endpoint: [<EzspStatus.SUCCESS: 0>]
2022-10-07 20:14:16.208 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addEndpoint: (2, 49246, <DeviceType.CONTROLLER: 2080>, 0, 1, 0, [0], [])
2022-10-07 20:14:16.260 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 2 (addEndpoint) received: b'00'
2022-10-07 20:14:16.263 DEBUG (MainThread) [bellows.zigbee.application] Ezsp adding endpoint: [<EzspStatus.SUCCESS: 0>]
2022-10-07 20:14:16.263 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:14:16.314 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:16.315 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'
2022-10-07 20:14:16.315 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:14:16.368 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:16.370 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'
2022-10-07 20:14:16.370 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:14:16.422 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:14:16.423 INFO (MainThread) [bellows.zigbee.application] EZSP Radio manufacturer:
2022-10-07 20:14:16.423 INFO (MainThread) [bellows.zigbee.application] EZSP Radio board name:
2022-10-07 20:14:16.424 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:14:16.477 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'00'
2022-10-07 20:14:16.480 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkInit: ()
2022-10-07 20:14:16.529 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 23 (networkInit) received: b'00'
2022-10-07 20:14:16.531 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2022-10-07 20:14:16.534 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 25 (stackStatusHandler) received: b'90'
2022-10-07 20:14:16.585 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 40 (getNetworkParameters) received: b'0001c82b9606ccccccccc82b140b0000000000f8ff07'
2022-10-07 20:14:16.589 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2022-10-07 20:14:16.638 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 39 (getNodeId) received: b'0000'
2022-10-07 20:14:16.639 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2022-10-07 20:14:16.692 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 38 (getEui64) received: b'825542feff23a460'
2022-10-07 20:14:16.693 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2022-10-07 20:14:16.745 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000500'
2022-10-07 20:14:16.746 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2022-10-07 20:14:16.804 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'00030003a1409d46c82b9606a1409d46c82b960601a01b0000000000000000000000000000'
2022-10-07 20:14:16.808 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2022-10-07 20:14:16.858 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'001a00015a6967426565416c6c69616e6365303900a000000000000000825542feff23a460'
2022-10-07 20:14:16.862 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2022-10-07 20:14:16.909 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:14:16.911 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:14:16.962 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:16.964 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'
2022-10-07 20:14:16.964 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:14:17.015 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:17.016 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'
2022-10-07 20:14:17.016 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:14:17.068 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:14:17.070 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2022-10-07 20:14:17.122 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'08ffffffffffffffff'
2022-10-07 20:14:17.123 DEBUG (MainThread) [zigpy.application] Network info: NetworkInfo(extended_pan_id=cc:cc:cc:cc:06:96:2b:c8, pan_id=0x2BC8, 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:9d:46:c8:2b:96:06:a1:40:9d:46:c8:2b:96:06, tx_counter=1810433, 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=40960, rx_counter=0, seq=0, partner_ieee=60:a4:23:ff:fe:42:55:82), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'ezsp': {'manufacturer': '', 'board': '', 'version': '6.7.9.0 build 405', 'stack_version': 8, 'can_write_custom_eui64': True}}, source='bellows@0.34.1')
2022-10-07 20:14:17.124 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:14:17.175 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'02'
2022-10-07 20:14:17.177 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TC_KEY_REQUEST_POLICY: 5>, <EzspDecisionId.ALLOW_TC_KEY_REQUESTS: 81>)
2022-10-07 20:14:17.230 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:17.232 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.IGNORE_UNSECURED_REJOINS|ALLOW_JOINS: 9>)
2022-10-07 20:14:17.283 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:17.285 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.APP_KEY_REQUEST_POLICY: 6>, <EzspDecisionId.DENY_APP_KEY_REQUESTS: 96>)
2022-10-07 20:14:17.335 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:17.336 DEBUG (MainThread) [bellows.ezsp.protocol] Send command networkState: ()
2022-10-07 20:14:17.389 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 24 (networkState) received: b'02'
2022-10-07 20:14:17.391 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNetworkParameters: ()
2022-10-07 20:14:17.445 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 40 (getNetworkParameters) received: b'0001c82b9606ccccccccc82b140b0000000000f8ff07'
2022-10-07 20:14:17.449 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getNodeId: ()
2022-10-07 20:14:17.498 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 39 (getNodeId) received: b'0000'
2022-10-07 20:14:17.499 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getEui64: ()
2022-10-07 20:14:17.552 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 38 (getEui64) received: b'825542feff23a460'
2022-10-07 20:14:17.553 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>,)
2022-10-07 20:14:17.606 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'000500'
2022-10-07 20:14:17.609 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.CURRENT_NETWORK_KEY: 3>,)
2022-10-07 20:14:17.662 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'00030003a1409d46c82b9606a1409d46c82b960601a01b0000000000000000000000000000'
2022-10-07 20:14:17.665 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getKey: (<EmberKeyType.TRUST_CENTER_LINK_KEY: 1>,)
2022-10-07 20:14:17.718 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 106 (getKey) received: b'001a00015a6967426565416c6c69616e6365303900a000000000000000825542feff23a460'
2022-10-07 20:14:17.722 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getCurrentSecurityState: ()
2022-10-07 20:14:17.768 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 105 (getCurrentSecurityState) received: b'007c00825542feff23a460'
2022-10-07 20:14:17.770 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_STRING: 1>,)
2022-10-07 20:14:17.823 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:17.824 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'
2022-10-07 20:14:17.825 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_BOARD_NAME: 2>,)
2022-10-07 20:14:17.877 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'10ffffffffffffffffffffffffffffffff'
2022-10-07 20:14:17.878 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'
2022-10-07 20:14:17.878 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_VERSION_INFO: 17>,)
2022-10-07 20:14:17.929 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 170 (getValue) received: b'0007950106070900aa'
2022-10-07 20:14:17.931 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMfgToken: (<EzspMfgTokenId.MFG_CUSTOM_EUI_64: 12>,)
2022-10-07 20:14:17.984 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 11 (getMfgToken) received: b'08ffffffffffffffff'
2022-10-07 20:14:17.987 DEBUG (MainThread) [bellows.zigbee.application] Starting EZSP watchdog
2022-10-07 20:14:17.991 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (60:a4:23:ff:fe:42:55:82, True)
2022-10-07 20:14:18.037 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 126 (setExtendedTimeout) received: b''
2022-10-07 20:14:18.038 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0000, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=1), 2, b'\x01\x00\x00')
2022-10-07 20:14:18.094 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0041'
2022-10-07 20:14:18.101 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000020000004001000041ff000000ffff03010000'
2022-10-07 20:14:18.106 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x01\x00\x00'
2022-10-07 20:14:18.110 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000042ff000000ffff110100000000408fcdab528000412c800000'
2022-10-07 20:14:18.117 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'\x01\x00\x00\x00\x00@\x8f\xcd\xabR\x80\x00A,\x80\x00\x00'
2022-10-07 20:14:18.120 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'0000000000020000004001000041020000'
2022-10-07 20:14:18.126 INFO (MainThread) [zigpy.device] [0x0000] Already have endpoints: {0: <bellows.zigbee.device.EZSPZDOEndpoint object at 0x7fa02b108430>, 1: <EZSPEndpoint id=1 in=[] out=[] status=<Status.NEW: 0>>}
2022-10-07 20:14:18.126 INFO (MainThread) [zigpy.device] [0x0000] Initializing endpoints [<EZSPEndpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
2022-10-07 20:14:18.129 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0000, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=3), 4, b'\x03\x00\x00\x01')
2022-10-07 20:14:18.158 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0043'
2022-10-07 20:14:18.164 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000040000004001000043ff000000ffff0403000001'
2022-10-07 20:14:18.169 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 4: b'\x03\x00\x00\x01'
2022-10-07 20:14:18.174 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000048000004001000044ff000000ffff1f030000001a01040100040005000006000a0019000105040100200000050205'
2022-10-07 20:14:18.179 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'\x03\x00\x00\x00\x1a\x01\x04\x01\x00\x04\x00\x05\x00\x00\x06\x00\n\x00\x19\x00\x01\x05\x04\x01\x00 \x00\x00\x05\x02\x05'
2022-10-07 20:14:18.181 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'0000000000040000004001000043040000'
2022-10-07 20:14:18.189 INFO (MainThread) [zigpy.device] [0x0000] Discovered basic device information for <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=True>
2022-10-07 20:14:18.189 DEBUG (MainThread) [zigpy.application] Device is initialized <EZSPCoordinator model='EZSP' manuf='Silicon Labs' nwk=0x0000 ieee=60:a4:23:ff:fe:42:55:82 is_initialized=True>
2022-10-07 20:14:18.190 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for Silicon Labs EZSP (60:a4:23:ff:fe:42:55:82)
2022-10-07 20:14:18.195 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getConfigurationValue: (<EzspConfigId.CONFIG_MULTICAST_TABLE_SIZE: 6>,)
2022-10-07 20:14:18.216 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 82 (getConfigurationValue) received: b'001000'
2022-10-07 20:14:18.216 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (0,)
2022-10-07 20:14:18.267 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.270 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (1,)
2022-10-07 20:14:18.322 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.325 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (2,)
2022-10-07 20:14:18.376 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.378 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (3,)
2022-10-07 20:14:18.429 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.432 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (4,)
2022-10-07 20:14:18.483 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.485 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (5,)
2022-10-07 20:14:18.536 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.538 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (6,)
2022-10-07 20:14:18.590 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.593 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (7,)
2022-10-07 20:14:18.643 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.645 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (8,)
2022-10-07 20:14:18.696 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.698 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (9,)
2022-10-07 20:14:18.750 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.752 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (10,)
2022-10-07 20:14:18.805 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.807 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (11,)
2022-10-07 20:14:18.858 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.860 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (12,)
2022-10-07 20:14:18.913 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.915 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (13,)
2022-10-07 20:14:18.966 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:18.968 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (14,)
2022-10-07 20:14:19.021 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:19.024 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getMulticastTableEntry: (15,)
2022-10-07 20:14:19.075 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 99 (getMulticastTableEntry) received: b'00000000'
2022-10-07 20:14:19.078 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setMulticastTableEntry: (0, EmberMulticastTableEntry(multicastId=0x0000, endpoint=1, networkIndex=0))
2022-10-07 20:14:19.128 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 100 (setMulticastTableEntry) received: b'00'
2022-10-07 20:14:19.132 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (0xfffc, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=5), 0, 6, b'\x05\x00\x00')
2022-10-07 20:14:19.137 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57])
2022-10-07 20:14:19.186 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 54 (sendBroadcast) received: b'0045'
2022-10-07 20:14:19.189 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'050000360000000001000045ff000000ffff03050000'
2022-10-07 20:14:19.198 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 175 (addTransientLinkKey) received: b'00'
2022-10-07 20:14:19.199 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.ALLOW_UNSECURED_REJOINS|ALLOW_JOINS: 3>)
2022-10-07 20:14:19.254 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 85 (setPolicy) received: b'00'
2022-10-07 20:14:20.146 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'06fcff0000360000000001000045066600'
2022-10-07 20:14:20.167 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 3 of 3)
2022-10-07 20:14:21.256 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.IGNORE_UNSECURED_REJOINS|ALLOW_JOINS: 9>)
File "/usr/local/lib/python3.10/site-packages/bellows/ezsp/v8/__init__.py", line 51, in pre_permit
puddly commented 1 year ago

Can you post the unredacted log file please (or email it to me)? You can strip out the person entities but it's still missing crucial context:

2022-10-07 20:14:15.179 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setConfigurationValue: (<EzspConfigId.CONFIG_SECURITY_LEVEL: 13>, 5)
File "/usr/local/lib/python3.10/site-packages/bellows/ezsp/v8/__init__.py", line 51, in pre_permit
2022-10-07 20:14:15.231 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 83 (setConfigurationValue) received: b'00'
blackscreener commented 1 year ago

OK, check your mail box.

puddly commented 1 year ago

Thanks. Can you also downgrade to 2022.9.x and send me a log file of it working? Does it always fail to start up with 2022.10.1? I'm testing with a similar firmware version and cannot replicate the problem.

To rule out runtime load, can you reload the ZHA integration a minute or two after it fails to start?

blackscreener commented 1 year ago

Thanks. Can you also downgrade to 2022.9.x and send me a log file of it working? Does it always fail to start up with 2022.10.1? I'm testing with a similar firmware version and cannot replicate the problem.

To rule out runtime load, can you reload the ZHA integration a minute or two after it fails to start?

Yes, it always fails to start up with 2022.10.1. Check your mail box for logs with downgraded HA to 2022.9.7. I will upgrade HA to 2022.10.1 and try to reload the ZHA integration.

puddly commented 1 year ago

Got it. Looks like the same error is happening with the previous version, it's just being ignored:

2022-10-08 18:48:27.999 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=145), 6, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2022-10-08 18:48:37.059 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=31, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=0), 0, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2022-10-08 18:48:55.971 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=31, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=0), 2, <EmberStatus.DELIVERY_FAILED: 102>, b'']

Are you currently using a USB 2.0 extension cable with your stick? If not, can you try again with one?

blackscreener commented 1 year ago

It's Zigbee over WiFi. obraz

ocxo commented 1 year ago

Are you currently using a USB 2.0 extension cable with your stick? If not, can you try again with one?

Out if curiosity, what would an extension cable change? I am wondering if this is related to any usb antennae problems I am having.

puddly commented 1 year ago

Active USB 3.0 ports interfere with 2.4GHz wireless devices. Zigbee specifically is a low-power protocol and will be significantly affected by active USB 3.0 ports, 2.4GHz WiFi, solid state drives, motherboards, etc. Using a USB 2.0 cable (or any cable plugged into a USB 2.0 port) lets you better position the coordinator and also reduce the impact of USB 3.0 devices.

blackscreener commented 1 year ago

ZHA is working again with HA 2022.10.2.