Zigbee coordinator 1A86:7523 cannot start or pair any devices

gianfrancomac · March 9, 2023, 8:48pm

I got a ZB-GW04 v1.2 USB key from Aliexpress (device id 1A86:7523) which came pre-flashed with EmberZNet 6.7.9.

I tried these 3 solutions:

ZHA - Integration sees the coordinator, but it’s unable to pair any devices. I enabled debug logs and see this every 10 seconds:

2023-03-09 15:30:40.829 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-03-09 15:30:40.830 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'465921a9a52a51fb7e'
2023-03-09 15:30:40.847 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6559a1a9a52a15b275944a25aa5592499c4e27abedce678bfdc66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca65a6459a4d9e4f9ff7c3d9d46a35a251904824c4187e'
2023-03-09 15:30:40.848 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-03-09 15:30:40.848 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[0, 44, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 44, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-03-09 15:30:40.849 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-03-09 15:30:40.850 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'575e21a9fe2a162a477e'
2023-03-09 15:30:40.854 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'765ea1a9fe2a15b3a091cc7e'
2023-03-09 15:30:40.854 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-03-09 15:30:40.855 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf9']
2023-03-09 15:30:40.856 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 249
2023-03-09 15:30:40.856 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 1, MAC_TX_BROADCAST = 384, MAC_RX_UNICAST = 0, MAC_TX_UNICAST_SUCCESS = 0, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 1, APS_DATA_RX_BROADCAST = 1, APS_DATA_TX_BROADCAST = 1, APS_DATA_RX_UNICAST = 4, APS_DATA_TX_UNICAST_SUCCESS = 2, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 0, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 0, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 385, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 249]

Z2M (Zigbee2mqtt). Here is the log:

[15:36:35] INFO: Preparing to start...
[15:36:35] INFO: Socat not enabled
[15:36:35] INFO: Zigbee Herdsman debug logging enabled
[15:36:36] INFO: Starting Zigbee2MQTT...
Zigbee2MQTT:debug 2023-03-09 15:36:38: Can't load state from file /config/zigbee2mqtt/state.json (doesn't exist)
Zigbee2MQTT:info  2023-03-09 15:36:38: Logging to console and directory: '/config/zigbee2mqtt/log/2023-03-09.15-36-38' filename: log.txt
Zigbee2MQTT:debug 2023-03-09 15:36:38: Removing old log directory '/config/zigbee2mqtt/log/2023-03-07.23-54-47'
Zigbee2MQTT:info  2023-03-09 15:36:38: Starting Zigbee2MQTT version 1.30.2 (commit #unknown)
Zigbee2MQTT:info  2023-03-09 15:36:38: Starting zigbee-herdsman (0.14.96)
Zigbee2MQTT:debug 2023-03-09 15:36:38: Using zigbee-herdsman with settings: '{"adapter":{"concurrent":null,"delay":null,"disableLED":false},"backupPath":"/config/zigbee2mqtt/coordinator_backup.json","databaseBackupPath":"/config/zigbee2mqtt/database.db.backup","databasePath":"/config/zigbee2mqtt/database.db","network":{"channelList":[11],"extendedPanID":[221,221,221,221,221,221,221,221],"networkKey":"HIDDEN","panID":6754},"serialPort":{"path":"/dev/ttyUSB0"}}'
2023-03-09T20:36:38.508Z zigbee-herdsman:adapter Failed to validate path: 'Error: spawn udevadm ENOENT'
2023-03-09T20:36:38.509Z zigbee-herdsman:controller:log Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13],"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"path":"/dev/ttyUSB0"},"databasePath":"/config/zigbee2mqtt/database.db","databaseBackupPath":"/config/zigbee2mqtt/database.db.backup","backupPath":"/config/zigbee2mqtt/coordinator_backup.json","adapter":{"disableLED":false,"concurrent":null,"delay":null}}'
2023-03-09T20:36:38.510Z zigbee-herdsman:adapter:zStack:znp:log Opening SerialPort with {"path":"/dev/ttyUSB0","baudRate":115200,"rtscts":false,"autoOpen":false}
2023-03-09T20:36:38.517Z zigbee-herdsman:adapter:zStack:znp:log Serialport opened
2023-03-09T20:36:38.518Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1}
2023-03-09T20:36:38.519Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32]
2023-03-09T20:36:38.772Z zigbee-herdsman:adapter:zStack:znp:log Writing CC2530/CC2531 skip bootloader payload
2023-03-09T20:36:38.773Z zigbee-herdsman:adapter:zStack:unpi:writer --> buffer [239]
2023-03-09T20:36:39.775Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1}
2023-03-09T20:36:39.776Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32]
2023-03-09T20:36:40.028Z zigbee-herdsman:adapter:zStack:znp:log Skip bootloader for CC2652/CC1352
2023-03-09T20:36:40.484Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1}
2023-03-09T20:36:40.485Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32]
2023-03-09T20:36:46.489Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1}
2023-03-09T20:36:46.490Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32]
2023-03-09T20:36:52.495Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1}
2023-03-09T20:36:52.496Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32]
Zigbee2MQTT:error 2023-03-09 15:36:58: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2023-03-09 15:36:58: Failed to start zigbee
Zigbee2MQTT:error 2023-03-09 15:36:58: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
Zigbee2MQTT:error 2023-03-09 15:36:58: Exiting...
Zigbee2MQTT:error 2023-03-09 15:36:58: Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
    at ZStackAdapter.start (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/zStackAdapter.ts:103:27)
    at Controller.start (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:132:29)
    at Zigbee.start (/app/lib/zigbee.ts:58:27)
    at Controller.start (/app/lib/controller.ts:101:27)
    at start (/app/index.js:107:5)

deCONZ. Here is the log:

cont-init: info: /etc/cont-init.d/nginx.sh exited 0
cont-init: info: running /etc/cont-init.d/novnc.sh
cont-init: info: /etc/cont-init.d/novnc.sh exited 0
s6-rc: info: service legacy-cont-init successfully started
s6-rc: info: service legacy-services: starting
services-up: info: copying legacy longrun deconz (no readiness notification)
services-up: info: copying legacy longrun nginx (no readiness notification)
services-up: info: copying legacy longrun otau-deconz (no readiness notification)
services-up: info: copying legacy longrun otau-ikea (no readiness notification)
services-up: info: copying legacy longrun otau-ledvance (no readiness notification)
services-up: info: copying legacy longrun websockify (no readiness notification)
[15:38:15] INFO: Running the deCONZ OTA updater...
[15:38:15] INFO: Running the OSRAM LEdvance OTA updater...
s6-rc: info: service legacy-services successfully started
[15:38:15] INFO: Websockify waiting for VNC to start
[15:38:15] INFO: Running the IKEA OTA updater...
[15:38:15] INFO: Starting VNC server (local/yes)...
[15:38:15] INFO: Starting websockify...
WebSocket server settings:
  - Listen on 127.0.0.1:5901
  - Flash security policy server
  - Web server. Web root: /usr/share/novnc
  - No SSL/TLS support (no cert file)
  - proxying from 127.0.0.1:5901 to 127.0.0.1:5900
[15:38:18] INFO: deCONZ waiting for VNC to start
[15:38:18] INFO: Starting the deCONZ gateway...
libpng warning: iCCP: known incorrect sRGB profile
libpng warning: iCCP: known incorrect sRGB profile
[15:38:19] INFO: Starting Nginx...
2023/03/09 15:38:19 [notice] 120#120: using the "epoll" event method
2023/03/09 15:38:19 [notice] 120#120: nginx/1.14.2
2023/03/09 15:38:19 [notice] 120#120: OS: Linux 5.4.0-144-generic
2023/03/09 15:38:19 [notice] 120#120: getrlimit(RLIMIT_NOFILE): 1048576:1048576
2023/03/09 15:38:19 [notice] 120#120: start worker processes
2023/03/09 15:38:19 [notice] 120#120: start worker process 678
15:38:19:316 HTTP Server listen on address 0.0.0.0, port: 40850, root: /usr/share/deCONZ/webapp/
15:38:19:320 /data/.local/share/dresden-elektronik/deCONZ/config.ini exists and is writeable
15:38:19:320 /data/.local/share/dresden-elektronik/deCONZ/zll.db exists and is writeable
15:38:19:336 COM: use stable device path /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0
15:38:19:352 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:38:19:352 ZCLDB init file /data/.local/share/dresden-elektronik/deCONZ/zcldb.txt
15:38:19:388 DDF enabled for Gold status
15:38:19:518 parent process s6-supervise
15:38:19:518 gw run mode: docker/hassio
15:38:19:518 GW sd-card image version file does not exist: /data/.local/share/dresden-elektronik/deCONZ/gw-version
15:38:19:518 DB sqlite version 3.27.2
15:38:19:519 DB PRAGMA page_count: 46
15:38:19:519 DB PRAGMA page_size: 4096
15:38:19:519 DB PRAGMA freelist_count: 0
15:38:19:519 DB file size 188416 bytes, free pages 0
15:38:19:519 DB PRAGMA user_version: 9
15:38:19:519 DB cleanup
15:38:19:519 DB create temporary views
15:38:19:521 Started websocket server on 0.0.0.0, port: 8081
15:38:19:521 [INFO] - Found file containing button maps. Parsing data...
15:38:19:525 [INFO] - Button maps loaded.
15:38:19:525 found node plugin: libde_rest_plugin.so - REST API Plugin
15:38:19:526 found node plugin: libde_signal_plugin.so - Signal Monitor Plugin
15:38:20:511 OTAU: create 1189-0033-01056400.zigbee
15:38:20:521 OTAU: create 1189-0033-01056400.zigbee
15:38:20:531 OTAU: create 1189-001B-00102428.zigbee
15:38:20:542 OTAU: create 1189-001A-00102428.zigbee
15:38:20:552 OTAU: create 1189-000F-00102428.zigbee
15:38:20:616 OTAU: create 1189-008C-02056550.zigbee
15:38:20:626 OTAU: create 1189-00A3-02136550.zigbee
15:38:20:634 OTAU: create 110C-006B-01020510.zigbee
15:38:20:641 OTAU: create 110C-0062-01020510.zigbee
15:38:20:642 found node plugin: libstd_otau_plugin.so - STD OTAU Plugin
parse error: Invalid numeric literal at line 1, column 34
15:38:20:737 dlg action: Read binding table
15:38:21:593 Skip idle timer callback, too early: elapsed 863 msec
15:38:21:668 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:38:25:469 Announced to internet https://phoscon.de/discover
15:38:27:121 DEV Tick.Init: booted after 8000 seconds
15:38:32:613 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:38:40:591 start reconnect to network
15:38:45:842 failed to reconnect to network try=1
15:38:46:615 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:38:51:090 try to reconnect to network try=2
15:38:56:090 try to reconnect to network try=3
15:38:56:614 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:39:01:090 failed to reconnect to network try=4
15:39:06:091 failed to reconnect to network try=5
15:39:10:613 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:39:11:091 failed to reconnect to network try=6
15:39:16:091 try to reconnect to network try=7
15:39:20:613 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:39:21:106 failed to reconnect to network try=8
15:39:26:091 failed to reconnect to network try=9
15:39:31:091 failed to reconnect to network try=10
15:39:34:613 COM: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0 / serialno: , ConBee
15:39:36:091 reconnect network failed, try later

I also flashed EmberZNet 6.10.3.0 build 297 (EZSP v8) - file: https://github.com/xsp1989/zigbeeFirmware/blob/fb955a14dae0e7e5f84dd0c0a81d4b736cfc5d83/firmware/Zigbee3.0_Dongle-NoSigned/EZSP/ncp-uart-sw_6.10.3_115200.gbl and got the same results.

Here is the output of ncp.py:

root@3cf879da1dbe:/tmp/silabs# ./ncp.py scan
Connecting to.. /dev/ttyUSB0 115200 True False
{"ports": [{"port": "/dev/ttyUSB0", "vid": "1A86", "pid": "7523", "deviceType": "zigbee", "stackVersion": "6.10.3-297"}]}

Any clues on what’s wrong or what else I can try?

Hedda · March 10, 2023, 3:23pm

First of all, read and follow this best practice guide regardless of which Zigbee gateway solution you are using → https://community.home-assistant.io/t/guide-for-zigbee-interference-avoidance-and-network-range-coverage-optimization/515752

Also note that you can not have more than one Zigbee gateway solution connected to the same Zigbee Coordinator adapter. If you want to use more than one Zigbee gateway solution then you need to buy more Zigbee Coordinator adapters as each needs it won separate dedicated adapter, (though tip is that it is not actually recommended to use more than one Zigbee gateway solution at any one time anyway).

That is a common symptom of electromagnetic interference, check out ZHA docs → https://www.home-assistant.io/integrations/zha#zigbee-interference-avoidance-and-network-rangecoverage-optimization

Again, strongly recommend that you read and follow all the best practice tips (regardless of which Zigbee gateway solution you are using) and watch the linked video there which clearly shows what can happen with interference → https://community.home-assistant.io/t/guide-for-zigbee-interference-avoidance-and-network-range-coverage-optimization/515752

The adapter will probably work fine with that configuration if you get it away from interference. Connect it via a USB extension cable to a USB 2.0 port (or powered USB 2.0 hub) and not to a USB 3.0 port/hub.

Looks like have not configued radio adapter type as “ezsp” in which case Z2M defaults to “zstack/znp”.

Log show zigbee-herdsman failing to connect to the serial adapter (because is tries “zstack/znp” adapter) so likley wrong adapter settings → https://www.zigbee2mqtt.io/guide/configuration/adapter-settings.html

If you are using Zigbee2MQTT Docker Container then also probably need to configure device using Docker Compose or manually forward serial device from the host machine to the Docker instance.

See example for Zigbee2MQTT configuration.yaml specifically for Home Assistant SkyConnect, but note that the ZB-GW04 dongle (a.k.a. easyiot stick) might use a different speed for baudrate (though it probably uses 115200 too) plus it probably do not use Software Flow Control instead of Hardware Flow Control and if so need to set rtscts as false (or remove it as default for rtscts is false).

serial:
  adapter: ezsp
  port: /dev/serial/by-id/usb-Nabu_Casa_SkyConnect_v1.0_-if00-port0
  baudrate: 115200
  rtscts: true

deCONZ/Phoscon does not support non-deconz adapters, it is only compatible with ConBee and RaspBee adapters, and you will never ever be able to use any other adapters with deCONZ/Phoscon. See → https://phoscon.de/en/conbee/software and https://phoscon.de/en/app/doc

gianfrancomac · March 11, 2023, 9:24pm

Thanks so much @Hedda !

I have read all those topics before diving into Zigbee and deciding to buy some devices to play with, but got stuck on that pairing issue for hours.

You pointed out about the interference problem, honestly I did not know it would matter this much, but indeed I was able to pair my devices with ZHA after adding a USB2 cable to stay away from USB3 circuitry.

When it was connected to the USB3 port, even putting the Zigbee device right next to the coordinator did not help, so USB3 seems to inhibit Zigbee communications like if you had jammer next to it. I would say going with a USB2 cable should be the first thing to try when troubleshooting Zigbee connections.

I wanted to try Z2M as well, it saw my dongle after setting ‘adapter: ezsp’. I was assuming it was the default type. Unfortunately the container still shuts down after a few seconds. I tried ‘rtscts: false’ as well.

Can you tell what’s wrong from the logs? I don’t get why it shuts down.

2023-03-11T20:53:19.458Z zigbee-herdsman:controller:database:log Writing database to '/config/zigbee2mqtt/database.db'
2023-03-11T20:53:19.472Z zigbee-herdsman:controller:log Disable joining
2023-03-11T20:53:19.473Z zigbee-herdsman:adapter:ezsp:ezsp ==> sendMulticast: {"apsFrame":{"clusterId":33,"profileId":41440,"sequence":7,"sourceEndpoint":242,"destinationEndpoint":242,"groupId":65533,"options":256},"hops":0,"nonmemberRadius":3,"messageTag":8,"message":{"type":"Buffer","data":[25,3,2,10,0,0]}}
2023-03-11T20:53:19.473Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"sendMulticast","_id_":56,"_isRequest_":true,"apsFrame":{"clusterId":33,"profileId":41440,"sequence":7,"sourceEndpoint":242,"destinationEndpoint":242,"groupId":65533,"options":256},"hops":0,"nonmemberRadius":3,"messageTag":8,"message":{"type":"Buffer","data":[25,3,2,10,0,0]}}
2023-03-11T20:53:19.474Z zigbee-herdsman:adapter:ezsp:uart --> DATA (3,0,0): 3300013800e0a12100f2f20001fdff07000308061903020a0000
2023-03-11T20:53:19.474Z zigbee-herdsman:adapter:ezsp:uart --> [307121a96c2af57d337894b8d7aa546fb69b4e24a3ebd76489f7c66332a37e]
2023-03-11T20:53:19.474Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (4)
2023-03-11T20:53:19.481Z zigbee-herdsman:adapter:ezsp:uart <-- [0471a1a96c2a159ccd977e]
2023-03-11T20:53:19.482Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (0,4,0): 0471a1a96c2a159ccd977e
2023-03-11T20:53:19.482Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (1)
2023-03-11T20:53:19.482Z zigbee-herdsman:adapter:ezsp:uart --> [8160597e]
2023-03-11T20:53:19.482Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 3380013800002e
2023-03-11T20:53:19.482Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x38: {"_cls_":"sendMulticast","_id_":56,"_isRequest_":false,"status":0,"sequence":46}
2023-03-11T20:53:19.482Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (4) success
2023-03-11T20:53:19.684Z zigbee-herdsman:adapter:ezsp:ezsp ==> permitJoining: {"duration":0}
2023-03-11T20:53:19.684Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"permitJoining","_id_":34,"_isRequest_":true,"duration":0}
2023-03-11T20:53:19.684Z zigbee-herdsman:adapter:ezsp:uart --> DATA (4,1,0): 340001220000
2023-03-11T20:53:19.684Z zigbee-herdsman:adapter:ezsp:uart --> [417621a9762a1526cb7e]
2023-03-11T20:53:19.685Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (5)
2023-03-11T20:53:19.689Z zigbee-herdsman:adapter:ezsp:uart <-- [1576a1a9762a152f4a7e]
2023-03-11T20:53:19.690Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (1,5,0): 1576a1a9762a152f4a7e
2023-03-11T20:53:19.690Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (2)
2023-03-11T20:53:19.690Z zigbee-herdsman:adapter:ezsp:uart --> [82503a7e]
2023-03-11T20:53:19.690Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 348001220000
2023-03-11T20:53:19.690Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x22: {"_cls_":"permitJoining","_id_":34,"_isRequest_":false,"status":0}
2023-03-11T20:53:19.691Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (5) success
2023-03-11T20:53:19.691Z zigbee-herdsman:controller:database:log Writing database to '/config/zigbee2mqtt/database.db'
2023-03-11T20:53:19.705Z zigbee-herdsman:controller:log Creating coordinator backup
2023-03-11T20:53:19.706Z zigbee-herdsman:adapter:ezsp:backup creating backup
2023-03-11T20:53:19.706Z zigbee-herdsman:adapter:ezsp:ezsp ==> version: {"desiredProtocolVersion":8}
2023-03-11T20:53:19.706Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":8}
2023-03-11T20:53:19.707Z zigbee-herdsman:adapter:ezsp:uart --> DATA (5,2,0): 350001000008
2023-03-11T20:53:19.707Z zigbee-herdsman:adapter:ezsp:uart --> [527721a9542a1de53c7e]
2023-03-11T20:53:19.707Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (6)
2023-03-11T20:53:19.712Z zigbee-herdsman:adapter:ezsp:uart <-- [2677a1a9542a1db069fec5907e]
2023-03-11T20:53:19.712Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (2,6,0): 2677a1a9542a1db069fec5907e
2023-03-11T20:53:19.712Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (3)
2023-03-11T20:53:19.712Z zigbee-herdsman:adapter:ezsp:uart --> [83401b7e]
2023-03-11T20:53:19.713Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 35800100000802306a
2023-03-11T20:53:19.713Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":8,"stackType":2,"stackVersion":27184}
2023-03-11T20:53:19.713Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (6) success
2023-03-11T20:53:19.713Z zigbee-herdsman:adapter:ezsp:ezsp ==> getKey: {"keyType":1}
2023-03-11T20:53:19.714Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"getKey","_id_":106,"_isRequest_":true,"keyType":1}
2023-03-11T20:53:19.714Z zigbee-herdsman:adapter:ezsp:uart --> DATA (6,3,0): 3600016a0001
2023-03-11T20:53:19.714Z zigbee-herdsman:adapter:ezsp:uart --> [637421a93e2a1407b37e]
2023-03-11T20:53:19.714Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (7)
2023-03-11T20:53:19.723Z zigbee-herdsman:adapter:ezsp:uart <-- [3774a1a93e2a15a859950210ebb8bebd4a530e68c3eb7fbb0e7b6399fc7d5e3f]
2023-03-11T20:53:19.724Z zigbee-herdsman:adapter:ezsp:uart <-- [a7ebcdde2c5dc4392458ab89d2577e]
2023-03-11T20:53:19.724Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (3,7,0): 3774a1a93e2a15a859950210ebb8bebd4a530e68c3eb7fbb0e7b6399fc7e3fa7ebcdde2c5dc4392458ab89d2577e
2023-03-11T20:53:19.724Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (4)
2023-03-11T20:53:19.725Z zigbee-herdsman:adapter:ezsp:uart --> [8430fc7e]
2023-03-11T20:53:19.725Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 3680016a00001a0001483541ed2cf4d61d29c32e251830f3bd00100000000000000043d23bfeff8d79e0
2023-03-11T20:53:19.725Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x6a: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"bitmask":26,"type":1,"key":{"contents":[72,53,65,237,44,244,214,29,41,195,46,37,24,48,243,189]},"outgoingFrameCounter":4096,"incomingFrameCounter":0,"sequenceNumber":0,"partnerEUI64":{"type":"Buffer","data":[224,121,141,255,254,59,210,67]}}}
2023-03-11T20:53:19.726Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (7) success
2023-03-11T20:53:19.726Z zigbee-herdsman:adapter:ezsp:ezsp ==> getNetworkParameters: null
2023-03-11T20:53:19.726Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true}
2023-03-11T20:53:19.726Z zigbee-herdsman:adapter:ezsp:uart --> DATA (7,4,0): 3700012800
2023-03-11T20:53:19.726Z zigbee-herdsman:adapter:ezsp:uart --> [747521a97c2ad9177e]
2023-03-11T20:53:19.726Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (0)
2023-03-11T20:53:19.734Z zigbee-herdsman:adapter:ezsp:uart <-- [4075a1a97c2a15b3844997f877884f94fe5422a0edce678bfd3e9c8e19017e]
2023-03-11T20:53:19.734Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (4,0,0): 4075a1a97c2a15b3844997f877884f94fe5422a0edce678bfd3e9c8e19017e
2023-03-11T20:53:19.734Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (5)
2023-03-11T20:53:19.734Z zigbee-herdsman:adapter:ezsp:uart --> [8520dd7e]
2023-03-11T20:53:19.735Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 37800128000001dddddddddddddddd621a050b0000000000f8ff07
2023-03-11T20:53:19.735Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[221,221,221,221,221,221,221,221],"panId":6754,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}}
2023-03-11T20:53:19.735Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (0) success
2023-03-11T20:53:19.735Z zigbee-herdsman:adapter:ezsp:ezsp ==> getKey: {"keyType":3}
2023-03-11T20:53:19.736Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"getKey","_id_":106,"_isRequest_":true,"keyType":3}
2023-03-11T20:53:19.736Z zigbee-herdsman:adapter:ezsp:uart --> DATA (0,5,0): 3800016a0003
2023-03-11T20:53:19.736Z zigbee-herdsman:adapter:ezsp:uart --> [057a21a93e2a16b56d7e]
2023-03-11T20:53:19.736Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (1)
2023-03-11T20:53:19.745Z zigbee-herdsman:adapter:ezsp:uart <-- [517aa1a93e2a15b159974b26af529b42914127a9e9c86f81f1cb67d9fc7d5e3f]
2023-03-11T20:53:19.746Z zigbee-herdsman:adapter:ezsp:uart <-- [a7ebcdde6f8fffc7dbd5d26980737e]
2023-03-11T20:53:19.746Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (5,1,0): 517aa1a93e2a15b159974b26af529b42914127a9e9c86f81f1cb67d9fc7e3fa7ebcdde6f8fffc7dbd5d26980737e
2023-03-11T20:53:19.746Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (6)
2023-03-11T20:53:19.747Z zigbee-herdsman:adapter:ezsp:uart --> [8610be7e]
2023-03-11T20:53:19.747Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 3880016a000003000301030507090b0d0f00020406080a0c0d0450000000000000000000000000000000
2023-03-11T20:53:19.748Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x6a: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"bitmask":3,"type":3,"key":{"contents":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]},"outgoingFrameCounter":20484,"incomingFrameCounter":0,"sequenceNumber":0,"partnerEUI64":{"type":"Buffer","data":[0,0,0,0,0,0,0,0]}}}
2023-03-11T20:53:19.748Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (1) success
2023-03-11T20:53:19.748Z zigbee-herdsman:adapter:ezsp:ezsp ==> getEui64: null
2023-03-11T20:53:19.748Z zigbee-herdsman:adapter:ezsp:ezsp ==> {"_cls_":"getEui64","_id_":38,"_isRequest_":true}
2023-03-11T20:53:19.748Z zigbee-herdsman:adapter:ezsp:uart --> DATA (1,6,0): 3900012600
2023-03-11T20:53:19.749Z zigbee-herdsman:adapter:ezsp:uart --> [167b21a9722ae1e87e]
2023-03-11T20:53:19.749Z zigbee-herdsman:adapter:ezsp:uart -?- waiting (2)
2023-03-11T20:53:19.754Z zigbee-herdsman:adapter:ezsp:uart <-- [627ba1a9722a5660626ab5a8d3b5061b7e]
2023-03-11T20:53:19.754Z zigbee-herdsman:adapter:ezsp:uart <-- DATA (6,2,0): 627ba1a9722a5660626ab5a8d3b5061b7e
2023-03-11T20:53:19.755Z zigbee-herdsman:adapter:ezsp:uart --> ACK  (7)
2023-03-11T20:53:19.755Z zigbee-herdsman:adapter:ezsp:uart --> [87009f7e]
2023-03-11T20:53:19.755Z zigbee-herdsman:adapter:ezsp:ezsp <== Frame: 398001260043d23bfeff8d79e0
2023-03-11T20:53:19.755Z zigbee-herdsman:adapter:ezsp:ezsp <== 0x26: {"_cls_":"getEui64","_id_":38,"_isRequest_":false,"eui64":{"type":"Buffer","data":[224,121,141,255,254,59,210,67]}}
2023-03-11T20:53:19.755Z zigbee-herdsman:adapter:ezsp:uart -+- waiting (2) success
2023-03-11T20:53:19.760Z zigbee-herdsman:controller:log Wrote coordinator backup to '/config/zigbee2mqtt/coordinator_backup.json'
2023-03-11T20:53:19.761Z zigbee-herdsman:adapter:ezsp:driv Stop driver
2023-03-11T20:53:19.761Z zigbee-herdsman:adapter:ezsp:ezsp Stop ezsp
2023-03-11T20:53:19.769Z zigbee-herdsman:adapter:ezsp:uart Port closed
2023-03-11T20:53:19.769Z zigbee-herdsman:adapter:ezsp:ezsp Close ezsp
2023-03-11T20:53:19.770Z zigbee-herdsman:adapter:ezsp:driv Close driver
2023-03-11T20:53:19.770Z zigbee-herdsman:adapter:ezsp:ezsp Close ezsp
2023-03-11T20:53:19.770Z zigbee-herdsman:adapter:ezsp:driv Close driver
Zigbee2MQTT:info  2023-03-11 15:53:19: Stopped zigbee-herdsman

Hedda · March 12, 2023, 7:20am

Sorry, better if you ask them in their community discussions on GiHub → https://github.com/Koenkk/zigbee2mqtt/discussions

Might be that it sees you already have a Zigbee network on it so you could try to manually reset the adapter manually first with zigpy-cli https://github.com/zigpy/zigpy-cli

Migrating from existing network created in ZHA to Zigbee2MQTT is currently tricky to say the least, read discussion here → https://github.com/Koenkk/zigbee2mqtt/discussions/16478

gianfrancomac · March 13, 2023, 4:37am

@Hedda You guessed right about resetting the adapter!

For some reason zigpy-cli was throwing a timeout error trying to connect to the adapter. I unplugged and plugged in the dongle again and it was able to start.

[00:33:24] INFO: Preparing to start...
[00:33:25] INFO: Socat not enabled
[00:33:25] INFO: Starting Zigbee2MQTT...
Zigbee2MQTT:info  2023-03-13 00:33:27: Logging to console and directory: '/config/zigbee2mqtt/log/2023-03-13.00-33-27' filename: log.txt
Zigbee2MQTT:info  2023-03-13 00:33:27: Starting Zigbee2MQTT version 1.30.2 (commit #unknown)
Zigbee2MQTT:info  2023-03-13 00:33:27: Starting zigbee-herdsman (0.14.96)
Zigbee2MQTT:info  2023-03-13 00:33:32: zigbee-herdsman started (resumed)
Zigbee2MQTT:info  2023-03-13 00:33:32: Coordinator firmware version: '{"meta":{"maintrel":"3 ","majorrel":"6","minorrel":"10","product":8,"revision":"6.10.3.0 build 297"},"type":"EZSP v8"}'
Zigbee2MQTT:info  2023-03-13 00:33:33: Currently 0 devices are joined:
Zigbee2MQTT:info  2023-03-13 00:33:33: Zigbee: disabling joining new devices.
Zigbee2MQTT:info  2023-03-13 00:33:33: Connecting to MQTT server at mqtt://172.17.0.1
Zigbee2MQTT:info  2023-03-13 00:33:33: Connected to MQTT server
Zigbee2MQTT:info  2023-03-13 00:33:33: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload '{"state":"online"}'
Zigbee2MQTT:info  2023-03-13 00:33:33: Started frontend on port 0.0.0.0:8099
Zigbee2MQTT:info  2023-03-13 00:33:33: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload '{"state":"online"}'
Zigbee2MQTT:info  2023-03-13 00:33:33: Zigbee2MQTT started!