Sonoff Zigbee 3.0 USB Dongle Plus V2 and zigbee2mqtt

Configuration Zigbee
1

I just got a new dongle as in the title. I have not changed the firmware. I can confirm that the hardware works just fine as I can pair and operate Zigbee devices with it. But I would like to use zigbee2mqtt instead. But it has a hard time starting (i.e. it does NOT):

[14:45:38] INFO: Preparing to start...
[14:45:38] INFO: Socat not enabled
[14:45:38] INFO: Starting Zigbee2MQTT...
Starting Zigbee2MQTT without watchdog.
[2025-04-18 14:45:39] info: 	z2m: Logging to console, file (filename: log.log)
[2025-04-18 14:45:39] info: 	z2m: Starting Zigbee2MQTT version 2.2.1 (commit #unknown)
[2025-04-18 14:45:39] info: 	z2m: Starting zigbee-herdsman (3.4.11)
[2025-04-18 14:45:40] info: 	zh:ember: Using default stack config.
[2025-04-18 14:45:40] info: 	zh:ember: ======== Ember Adapter Starting ========
[2025-04-18 14:45:40] info: 	zh:ember:ezsp: ======== EZSP starting ========
[2025-04-18 14:45:40] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 14:45:40] info: 	zh:ember:uart:ash: RTS/CTS config is off, enabling software flow control.
[2025-04-18 14:45:40] info: 	zh:ember:uart:ash: Serial port opened
[2025-04-18 14:45:40] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 14:45:42] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 14:45:42] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 14:45:45] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 14:45:45] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 14:45:47] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 14:45:47] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 14:45:50] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 14:45:50] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 14:45:52] error: 	z2m: Error while starting zigbee-herdsman
[2025-04-18 14:45:52] error: 	z2m: Failed to start zigbee-herdsman
[2025-04-18 14:45:52] error: 	z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start_crashes-runtime.html for possible solutions
[2025-04-18 14:45:52] error: 	z2m: Exiting...
[2025-04-18 14:45:52] error: 	z2m: Error: Failed to start EZSP layer with status=HOST_FATAL_ERROR.
    at EmberAdapter.initEzsp (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:672:19)
    at EmberAdapter.start (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:1538:24)
    at Controller.start (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/controller/controller.ts:136:29)
    at Zigbee.start (/app/lib/zigbee.ts:69:27)
    at Controller.start (/app/lib/controller.ts:104:13)
    at start (/app/index.js:149:5)

Here is my environment:
HA is running as a virtual machine in Proxmox.
HA recognizes it as:

device ID: /dev/ttyUSB0
ID: /dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_6c8d24a3d973ef11a446dd1e313510fd-if00-port0

Config:
socat:

enabled: false
master: pty,raw,echo=0,link=/tmp/ttyZ2M,mode=777
slave: tcp-listen:8485,keepalive,nodelay,reuseaddr,keepidle=1,keepintvl=1,keepcnt=5
options: "-d -d"
log: false

mqtt:

server: mqtt://1.2.3.4:1883

serial:

port: >-
  /dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_6c8d24a3d973ef11a446dd1e313510fd-if00-port0
adapter: ember

What could be the problem? Any pointers would be much appreciated as I’ve been trying to get it work for a couple of days now…

2

Also, when I try to update the dongle at this page (Silabs Firmware Flasher | Web based flasher for ZB-GW04 and ZBDongle-E. MultiPAN RCP firmware enables these devices to be used with Silabs Multiprotocol Addon in Home Assistant. Allow Zigbee and Thread to co-exist on the same dongle. Get ahead of the tech an experiment with Matter!), it fails with this log:

2025-04-18 16:07:10 emscripten zigpy.serial[42] INFO Using pyserial-asyncio-fast in place of pyserial-asyncio
2025-04-18 16:07:11 emscripten zigpy.appdb[42] DEBUG SQLite version for <webserial_transport.MockSqlite3 object at 0x12049b8>: 3.31.1
2025-04-18 16:07:11 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.GECKO_BOOTLOADER at 115200 baud
2025-04-18 16:07:11 emscripten zigpy.serial[42] DEBUG Connection made: <webserial_transport.WebSerialTransport object at 0x11d2630>
2025-04-18 16:07:11 emscripten universal_silabs_flasher.gecko_bootloader[42] DEBUG Sending data b'\n'
2025-04-18 16:07:11 emscripten universal_silabs_flasher.gecko_bootloader[42] DEBUG Sending data GeckoBootloaderOption.EBL_INFO
2025-04-18 16:07:11 emscripten universal_silabs_flasher.gecko_bootloader[42] DEBUG Parsing State.WAITING_FOR_MENU: bytearray(b'\x11')
2025-04-18 16:07:12 emscripten universal_silabs_flasher.gecko_bootloader[42] DEBUG Parsing State.WAITING_FOR_MENU: bytearray(b'\x11\x1a')
2025-04-18 16:07:13 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:13 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:13 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:13 emscripten zigpy.serial[42] DEBUG Connection lost: None
2025-04-18 16:07:13 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.CPC at 460800 baud
2025-04-18 16:07:13 emscripten zigpy.serial[42] DEBUG Connection made: <webserial_transport.WebSerialTransport object at 0x1ab1a90>
2025-04-18 16:07:13 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:13 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:14 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 1 of 4)
2025-04-18 16:07:14 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to parse buffer bytearray(b'x\x00\x80\xf8\x00x\x80\x00\x80\x00\xf8\xf8\x80\xf8\xf8x\xf8'): ValueError('Invalid flag')
2025-04-18 16:07:14 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:14 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:15 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 2 of 4)
2025-04-18 16:07:15 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:15 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:16 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 3 of 4)
2025-04-18 16:07:17 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:17 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:18 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 4 of 4)
2025-04-18 16:07:18 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:18 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:18 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:18 emscripten zigpy.serial[42] DEBUG Connection lost: None
2025-04-18 16:07:18 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.CPC at 115200 baud
2025-04-18 16:07:18 emscripten zigpy.serial[42] DEBUG Connection made: <webserial_transport.WebSerialTransport object at 0x2183c98>
2025-04-18 16:07:18 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:18 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:19 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 1 of 4)
2025-04-18 16:07:19 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to parse buffer bytearray(b'\x11\x1a\xc1\x02\x02\x9b{~'): ValueError('Invalid flag')
2025-04-18 16:07:19 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:19 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:20 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 2 of 4)
2025-04-18 16:07:20 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:20 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:21 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 3 of 4)
2025-04-18 16:07:21 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:21 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:22 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 4 of 4)
2025-04-18 16:07:22 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:22 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:22 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:22 emscripten zigpy.serial[42] DEBUG Connection lost: None
2025-04-18 16:07:22 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.CPC at 230400 baud
2025-04-18 16:07:22 emscripten zigpy.serial[42] DEBUG Connection made: <webserial_transport.WebSerialTransport object at 0x20dd880>
2025-04-18 16:07:22 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:22 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:23 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 1 of 4)
2025-04-18 16:07:23 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to parse buffer bytearray(b'\x06\xe0\x98\xe0\x06\x18\x18\x9e\xf8\x9e\x86\xfe'): ValueError('Invalid flag')
2025-04-18 16:07:23 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:23 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:24 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 2 of 4)
2025-04-18 16:07:24 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:24 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:25 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 3 of 4)
2025-04-18 16:07:25 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending frame CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b'')))
2025-04-18 16:07:25 emscripten universal_silabs_flasher.cpc[42] DEBUG Sending data b'\x14\x00\n\x00\xc4U\xd3\x02\x00\x04\x00\x03\x00\x00\x00\xdb\x12'
2025-04-18 16:07:26 emscripten universal_silabs_flasher.cpc[42] DEBUG Failed to send CPCTransportFrame(endpoint=<EndpointId.SYSTEM: 0>, control=196, payload=UnnumberedFrame(command_id=<UnnumberedFrameCommandId.PROP_VALUE_GET: 2>, command_seq=0, payload=PropertyCommand(property_id=<PropertyId.SECONDARY_CPC_VERSION: 3>, value=b''))), trying again in 0.10s (attempt 4 of 4)
2025-04-18 16:07:26 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:26 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:26 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:26 emscripten zigpy.serial[42] DEBUG Connection lost: None
2025-04-18 16:07:26 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.EZSP at 115200 baud
2025-04-18 16:07:27 emscripten zigpy.serial[42] DEBUG Connection made: <bellows.ash.AshProtocol object at 0x11d38d8>
2025-04-18 16:07:27 emscripten bellows.ezsp[42] DEBUG Resetting EZSP
2025-04-18 16:07:27 emscripten bellows.uart[42] DEBUG Resetting ASH
2025-04-18 16:07:27 emscripten bellows.ash[42] DEBUG Sending frame CANCEL + RstFrame() + FLAG
2025-04-18 16:07:27 emscripten bellows.ash[42] DEBUG Sending data  1ac038bc7e
2025-04-18 16:07:27 emscripten bellows.ash[42] DEBUG Received data 11
2025-04-18 16:07:27 emscripten bellows.ash[42] DEBUG Received XON byte, resuming transmission
2025-04-18 16:07:28 emscripten bellows.ash[42] DEBUG Received data 1a
2025-04-18 16:07:28 emscripten bellows.ash[42] DEBUG Received cancel byte, clearing buffer
2025-04-18 16:07:28 emscripten bellows.ash[42] DEBUG Received data c102029b7b7e
2025-04-18 16:07:28 emscripten bellows.ash[42] DEBUG Received frame RStackFrame(version=2, reset_code=<NcpResetCode.RESET_POWER_ON: 2>)
2025-04-18 16:07:32 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:32 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:32 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:32 emscripten zigpy.serial[42] DEBUG Connection lost: None
2025-04-18 16:07:32 emscripten bellows.uart[42] DEBUG Connection lost: None
2025-04-18 16:07:32 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.EZSP at 230400 baud
2025-04-18 16:07:32 emscripten zigpy.serial[42] DEBUG Connection made: <bellows.ash.AshProtocol object at 0x1c15a10>
2025-04-18 16:07:32 emscripten bellows.ezsp[42] DEBUG Resetting EZSP
2025-04-18 16:07:32 emscripten bellows.uart[42] DEBUG Resetting ASH
2025-04-18 16:07:32 emscripten bellows.ash[42] DEBUG Sending frame CANCEL + RstFrame() + FLAG
2025-04-18 16:07:32 emscripten bellows.ash[42] DEBUG Sending data  1ac038bc7e
2025-04-18 16:07:32 emscripten bellows.ash[42] DEBUG Received data 06e0
2025-04-18 16:07:33 emscripten bellows.ash[42] DEBUG Received data 98
2025-04-18 16:07:33 emscripten bellows.ash[42] DEBUG Received data e00618189ef89e86fe
2025-04-18 16:07:33 emscripten bellows.ash[42] DEBUG Received substitute byte, marking buffer as corrupted
2025-04-18 16:07:37 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:37 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:37 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:37 emscripten zigpy.serial[42] DEBUG Connection lost: None
2025-04-18 16:07:37 emscripten bellows.uart[42] DEBUG Connection lost: None
2025-04-18 16:07:37 emscripten universal_silabs_flasher.flasher[42] INFO Probing ApplicationType.SPINEL at 460800 baud
2025-04-18 16:07:37 emscripten zigpy.serial[42] DEBUG Connection made: <webserial_transport.WebSerialTransport object at 0x1dfad20>
2025-04-18 16:07:37 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02')
2025-04-18 16:07:37 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending data b'~\x83\x02\x02\xe65~'
2025-04-18 16:07:38 emscripten universal_silabs_flasher.spinel[42] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02'), trying again in 0.10s (attempt 1 of 4)
2025-04-18 16:07:38 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02')
2025-04-18 16:07:38 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending data b'~\x83\x02\x02\xe65~'
2025-04-18 16:07:39 emscripten universal_silabs_flasher.spinel[42] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02'), trying again in 0.10s (attempt 2 of 4)
2025-04-18 16:07:39 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02')
2025-04-18 16:07:39 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending data b'~\x83\x02\x02\xe65~'
2025-04-18 16:07:40 emscripten universal_silabs_flasher.spinel[42] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02'), trying again in 0.10s (attempt 3 of 4)
2025-04-18 16:07:40 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02')
2025-04-18 16:07:40 emscripten universal_silabs_flasher.spinel[42] DEBUG Sending data b'~\x83\x02\x02\xe65~'
2025-04-18 16:07:41 emscripten universal_silabs_flasher.spinel[42] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x02'), trying again in 0.10s (attempt 4 of 4)
2025-04-18 16:07:41 emscripten zigpy.serial[42] DEBUG Waiting for serial port to close
2025-04-18 16:07:41 emscripten webserial_transport[42] DEBUG Closing serial port
2025-04-18 16:07:41 emscripten webserial_transport[42] DEBUG Closed serial port
2025-04-18 16:07:41 emscripten zigpy.serial[42] DEBUG Connection lost: None
3

Well, after a lot of hassle, I was able to upgrade the firmware to 7.4.
Now zigbee2mqtt still doesn’t start, but with a different error:

[16:37:32] INFO: Preparing to start...
[16:37:32] INFO: Socat not enabled
[16:37:32] ERROR: Got unexpected response from the API: Service not enabled
[16:37:32] INFO: Starting Zigbee2MQTT...
Starting Zigbee2MQTT without watchdog.
[2025-04-18 16:37:33] info: 	z2m: Logging to console, file (filename: log.log)
[2025-04-18 16:37:34] info: 	z2m: Starting Zigbee2MQTT version 2.2.1 (commit #unknown)
[2025-04-18 16:37:34] info: 	z2m: Starting zigbee-herdsman (3.4.11)
[2025-04-18 16:37:34] info: 	zh:ember: Using default stack config.
[2025-04-18 16:37:34] info: 	zh:ember: ======== Ember Adapter Starting ========
[2025-04-18 16:37:34] info: 	zh:ember:ezsp: ======== EZSP starting ========
[2025-04-18 16:37:34] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 16:37:34] info: 	zh:ember:uart:ash: RTS/CTS config is off, enabling software flow control.
[2025-04-18 16:37:34] info: 	zh:ember:uart:ash: Serial port opened
[2025-04-18 16:37:34] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 16:37:36] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 16:37:36] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 16:37:39] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 16:37:39] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 16:37:41] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 16:37:41] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 16:37:44] info: 	zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-04-18 16:37:44] info: 	zh:ember:uart:ash: ======== ASH starting ========
[2025-04-18 16:37:46] error: 	z2m: Error while starting zigbee-herdsman
[2025-04-18 16:37:46] error: 	z2m: Failed to start zigbee-herdsman
[2025-04-18 16:37:46] error: 	z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start_crashes-runtime.html for possible solutions
[2025-04-18 16:37:46] error: 	z2m: Exiting...
[2025-04-18 16:37:46] error: 	z2m: Error: Failed to start EZSP layer with status=HOST_FATAL_ERROR.
    at EmberAdapter.initEzsp (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:672:19)
    at EmberAdapter.start (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:1538:24)
    at Controller.start (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/controller/controller.ts:136:29)
    at Zigbee.start (/app/lib/zigbee.ts:69:27)
    at Controller.start (/app/lib/controller.ts:104:13)
    at start (/app/index.js:149:5)
4

I figured it out!
For others who might be in the same shoes:

  1. I had to update the dongle to the latest firmware (it was on 6.something, now it is 7.4)
  2. I set the following configuration:
serial:
  port: >-
    /dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_6c8d24a3d973ef11a446dd1e313510fd-if00-port0
  adapter: ember
  baudrate: 115200

The default baudrate was incorrect!

1 Like