en mode debug
> zigbee2mqtt@2.7.1 start
> node index.js
Starting Zigbee2MQTT without watchdog.
[2025-12-22 17:16:27] e[34mdebuge[39m: z2m: Removing old log directory '/var/www/html/plugins/z2m/data/log/2025-12-22.16-31-25'
[2025-12-22 17:16:27] e[32minfoe[39m: z2m: Logging to console, file (filename: log.log)
[2025-12-22 17:16:28] e[34mdebuge[39m: z2m: Loaded state from file /var/www/html/plugins/z2m/data/state.json
[2025-12-22 17:16:28] e[32minfoe[39m: z2m: Starting Zigbee2MQTT version 2.7.1 (commit #8916fb7b)
[2025-12-22 17:16:28] e[32minfoe[39m: z2m: Starting zigbee-herdsman (7.0.4)
[2025-12-22 17:16:28] e[34mdebuge[39m: z2m: Using zigbee-herdsman with settings: '"{\"network\":{\"panID\":6754,\"extendedPanID\":[221,221,221,221,221,221,221,221],\"channelList\":[11],\"networkKey\":\"HIDDEN\"},\"databasePath\":\"/var/www/html/plugins/z2m/data/database.db\",\"databaseBackupPath\":\"/var/www/html/plugins/z2m/data/database.db.backup\",\"backupPath\":\"/var/www/html/plugins/z2m/data/coordinator_backup.json\",\"serialPort\":{\"baudRate\":115200,\"path\":\"tcp://192.168.3.251:6638\",\"adapter\":\"ember\"},\"adapter\":{\"disableLED\":false}}"'
[2025-12-22 17:16:28] e[32minfoe[39m: zh:ember: Using default stack config.
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"baudRate":115200,"path":"tcp://192.168.3.251:6638","adapter":"ember"},"adapter":{"disableLED":false},"databasePath":"/var/www/html/plugins/z2m/data/database.db","databaseBackupPath":"/var/www/html/plugins/z2m/data/database.db.backup","backupPath":"/var/www/html/plugins/z2m/data/coordinator_backup.json"}'
[2025-12-22 17:16:28] e[32minfoe[39m: zh:ember: ======== Ember Adapter Starting ========
[2025-12-22 17:16:28] e[32minfoe[39m: zh:ember:ezsp: ======== EZSP starting ========
[2025-12-22 17:16:28] e[32minfoe[39m: zh:ember:uart:ash: ======== ASH Adapter reset ========
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Opening TCP socket with 192.168.3.251:6638
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Socket connected
[2025-12-22 17:16:28] e[32minfoe[39m: zh:ember:uart:ash: Socket ready
[2025-12-22 17:16:28] e[32minfoe[39m: zh:ember:uart:ash: ======== ASH starting ========
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=RST]
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 0/5000
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 100/5000
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 200/5000
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 300/5000
[2025-12-22 17:16:28] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 400/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 500/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 600/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 700/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 800/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 900/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1000/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1100/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1200/5000
[2025-12-22 17:16:29] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1300/5000
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1400/5000
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1500/5000
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1600/5000
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: Waiting for RSTACK... 1700/5000
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=RSTACK]
[2025-12-22 17:16:30] e[32minfoe[39m: zh:ember:uart:ash: ======== ASH connected ========
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-12-22 17:16:30] e[32minfoe[39m: zh:ember:uart:ash: ======== ASH started ========
[2025-12-22 17:16:30] e[32minfoe[39m: zh:ember:ezsp: ======== EZSP started ========
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=0:"VERSION" Seq=0 Len=4]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=0](ackRx=0)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=0](frmRx=0) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=1)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=0:"VERSION" Seq=0 Len=7]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=0:"VERSION" Seq=1 Len=6]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=1](ackRx=1)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=0:"VERSION" Seq=1 Len=9]
[2025-12-22 17:16:30] e[32minfoe[39m: zh:ember: Adapter EZSP protocol version (14) lower than Host. Switched.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: Adapter info: EZSPVersion=14 StackType=2 StackVersion=32800
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=170:"GET_VALUE" Seq=2 Len=6]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=2](ackRx=2)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=170:"GET_VALUE" Seq=2 Len=17]
[2025-12-22 17:16:30] e[32minfoe[39m: zh:ember: Adapter version info: {"ezsp":14,"revision":"8.0.2 [GA]","build":397,"major":8,"minor":0,"patch":2,"special":0,"type":170}
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=3 Len=8]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=3](ackRx=3)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=3](frmRx=3) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=4)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=3 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'TRUST_CENTER_ADDRESS_CACHE_SIZE' TO '2' with status=OK.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=4 Len=8]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=4](ackRx=4)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=4](frmRx=4) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=5)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=4 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'INDIRECT_TRANSMISSION_TIMEOUT' TO '7680' with status=OK.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=5 Len=8]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=5](ackRx=5)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=5](frmRx=5) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=6)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=5 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'MAX_HOPS' TO '30' with status=OK.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=6 Len=8]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=6](ackRx=6)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=6](frmRx=6) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=7)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=6 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'SUPPORTED_NETWORKS' TO '1' with status=OK.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=7 Len=7]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=7](ackRx=7)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=7](frmRx=7) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=7 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: [EzspPolicyId] SET 'BINDING_MODIFICATION_POLICY' TO '18' with status=OK.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=8 Len=7]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=0](ackRx=0)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=0](frmRx=0) Added to rxQueue
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=1)
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=8 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember: [EzspPolicyId] SET 'MESSAGE_CONTENTS_IN_CALLBACK_POLICY' TO '64' with status=OK.
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=171:"SET_VALUE" Seq=9 Len=9]
[2025-12-22 17:16:30] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=1](ackRx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=171:"SET_VALUE" Seq=9 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspValueId] SET 'TRANSIENT_DEVICE_TIMEOUT' TO '16,39' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=21:"SET_MANUFACTURER_CODE" Seq=10 Len=7]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=2](ackRx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=21:"SET_MANUFACTURER_CODE" Seq=10 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=11 Len=8]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=3](ackRx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=3](frmRx=3) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=11 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'STACK_PROFILE' TO '2' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=12 Len=8]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=4](ackRx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=4](frmRx=4) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=5)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=12 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'SECURITY_LEVEL' TO '5' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=13 Len=8]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=5](ackRx=5)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=5](frmRx=5) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=6)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=13 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'MAX_END_DEVICE_CHILDREN' TO '32' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=14 Len=8]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=6](ackRx=6)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=6](frmRx=6) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=7)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=14 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'END_DEVICE_POLL_TIMEOUT' TO '8' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=15 Len=8]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=7](ackRx=7)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=7](frmRx=7) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=83:"SET_CONFIGURATION_VALUE" Seq=15 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspConfigId] SET 'TRANSIENT_KEY_TIMEOUT_S' TO '300' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=171:"SET_VALUE" Seq=16 Len=8]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=0](ackRx=0)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=0](frmRx=0) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=171:"SET_VALUE" Seq=16 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspValueId] SET 'CCA_THRESHOLD' TO '0' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=3:"GET_EXTENDED_VALUE" Seq=17 Len=10]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=1](ackRx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=3:"GET_EXTENDED_VALUE" Seq=17 Len=12]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=2:"ADD_ENDPOINT" Seq=18 Len=63]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=2](ackRx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=2:"ADD_ENDPOINT" Seq=18 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: Registered endpoint '1'.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=100:"SET_MULTICAST_TABLE_ENTRY" Seq=19 Len=10]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=3](ackRx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=3](frmRx=3) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=100:"SET_MULTICAST_TABLE_ENTRY" Seq=19 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: Registered multicast table entry (0): {"multicastId":0,"endpoint":1,"networkIndex":0}.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=100:"SET_MULTICAST_TABLE_ENTRY" Seq=20 Len=10]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=4](ackRx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=4](frmRx=4) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=5)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=100:"SET_MULTICAST_TABLE_ENTRY" Seq=20 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: Registered multicast table entry (1): {"multicastId":901,"endpoint":1,"networkIndex":0}.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=3:"GET_EXTENDED_VALUE" Seq=21 Len=10]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=5](ackRx=5)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=5](frmRx=5) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=6)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=3:"GET_EXTENDED_VALUE" Seq=21 Len=12]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=2:"ADD_ENDPOINT" Seq=22 Len=17]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=6](ackRx=6)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=6](frmRx=6) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=7)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=2:"ADD_ENDPOINT" Seq=22 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: Registered endpoint '242'.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=100:"SET_MULTICAST_TABLE_ENTRY" Seq=23 Len=10]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=7](ackRx=7)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=7](frmRx=7) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=100:"SET_MULTICAST_TABLE_ENTRY" Seq=23 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: Registered multicast table entry (2): {"multicastId":2948,"endpoint":242,"networkIndex":0}.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=24 Len=7]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=0](ackRx=0)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=0](frmRx=0) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=24 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspPolicyId] SET 'TC_KEY_REQUEST_POLICY' TO '81' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=25 Len=7]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=1](ackRx=1)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=25 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspPolicyId] SET 'APP_KEY_REQUEST_POLICY' TO '96' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=26 Len=7]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=2](ackRx=2)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=26 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [EzspPolicyId] SET 'TRUST_CENTER_POLICY' TO '3' with status=OK.
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: ===> [FRAME: ID=23:"NETWORK_INIT" Seq=27 Len=7]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=3](ackRx=3)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=3](frmRx=3) Added to rxQueue
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=4)
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember:ezsp: <=== [FRAME: ID=23:"NETWORK_INIT" Seq=27 Len=9]
[2025-12-22 17:16:31] e[34mdebuge[39m: zh:ember: [INIT TC] Network init status=NOT_JOINED.
[2025-12-22 17:16:31] e[31merrore[39m: z2m: Error while starting zigbee-herdsman
[2025-12-22 17:16:31] e[31merrore[39m: z2m: Failed to start zigbee-herdsman
[2025-12-22 17:16:31] e[31merrore[39m: z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start_crashes-runtime.html for possible solutions
[2025-12-22 17:16:31] e[31merrore[39m: z2m: Exiting...
[2025-12-22 17:16:32] e[31merrore[39m: z2m: Error: [BACKUP] Current backup file is not for EmberZNet stack.
at EmberAdapter.getStoredBackup (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/.pnpm/zigbee-herdsman@7.0.4/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:1154:23)
at EmberAdapter.initTrustCenter (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/.pnpm/zigbee-herdsman@7.0.4/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:925:29)
at processTicksAndRejections (node:internal/process/task_queues:105:5)
at runNextTicks (node:internal/process/task_queues:69:3)
at processImmediate (node:internal/timers:453:9)
at EmberAdapter.initEzsp (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/.pnpm/zigbee-herdsman@7.0.4/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:708:18)
at EmberAdapter.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/.pnpm/zigbee-herdsman@7.0.4/node_modules/zigbee-herdsman/src/adapter/ember/adapter/emberAdapter.ts:1539:24)
at Controller.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/.pnpm/zigbee-herdsman@7.0.4/node_modules/zigbee-herdsman/src/controller/controller.ts:143:29)
at Zigbee.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/lib/zigbee.ts:70:27)
at Controller.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/lib/controller.ts:101:13)