> zigbee2mqtt@1.41.0 start > node index.js Starting Zigbee2MQTT without watchdog. [2024-11-12 09:34:59] info: z2m: Logging to console, file (filename: log.log) [2024-11-12 09:34:59] error: z2m: Failed to load external converter file '/var/www/html/plugins/z2m/core/class/../config/converters/Danfoss/icon.js' (Cannot find module 'zigbee-herdsman-converters/lib/extend' Require stack: - /var/www/html/plugins/z2m/resources/zigbee2mqtt/dist/util/utils.js - /var/www/html/plugins/z2m/resources/zigbee2mqtt/dist/util/settings.js - /var/www/html/plugins/z2m/resources/zigbee2mqtt/index.js) [2024-11-12 09:34:59] error: z2m: Probably there is a syntax error in the file or the external converter is not compatible with the current Zigbee2MQTT version [2024-11-12 09:34:59] error: z2m: Note that external converters are not meant for long term usage, it's meant for local testing after which a pull request should be created to add out-of-the-box support for the device [2024-11-12 09:34:59] debug: z2m: Loaded state from file /var/www/html/plugins/z2m/data/state.json [2024-11-12 09:34:59] info: z2m: Starting Zigbee2MQTT version 1.41.0 (commit #cc31cea) [2024-11-12 09:34:59] debug: z2m: sd-notify loaded [2024-11-12 09:34:59] info: z2m: Starting zigbee-herdsman (2.1.7) [2024-11-12 09:34:59] debug: 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\":{\"path\":\"/dev/ttyLuna-Zigbee\",\"adapter\":\"ezsp\"},\"adapter\":{\"disableLED\":false}}"' [2024-11-12 09:35:00] debug: zh:ezsp: Adapter concurrent: 8 [2024-11-12 09:35:00] debug: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"path":"/dev/ttyLuna-Zigbee","adapter":"ezsp"},"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"}' [2024-11-12 09:35:01] warning: zh:ezsp: 'ezsp' driver is deprecated and will only remain to provide support for older firmware (pre 7.4.x). Migration to 'ember' is recommended. If using Zigbee2MQTT see https://github.com/Koenkk/zigbee2mqtt/discussions/21462 [2024-11-12 09:35:01] debug: zh:ezsp:uart: RTS/CTS config is off, enabling software flow control. [2024-11-12 09:35:01] debug: zh:ezsp:uart: Opening SerialPort with {"path":"/dev/ttyLuna-Zigbee","baudRate":115200,"rtscts":false,"autoOpen":false,"parity":"none","stopBits":1,"xon":true,"xoff":true} [2024-11-12 09:35:01] debug: zh:ezsp:uart: Serialport opened [2024-11-12 09:35:01] debug: zh:ezsp:uart: Uart reseting [2024-11-12 09:35:01] debug: zh:ezsp:uart: --> Write reset [2024-11-12 09:35:01] debug: zh:ezsp:uart: --> [1ac038bc7e] [2024-11-12 09:35:01] debug: zh:ezsp:uart: -?- waiting reset [2024-11-12 09:35:02] debug: zh:ezsp:uart: <-- [c1020b0a527e] [2024-11-12 09:35:02] debug: zh:ezsp:uart: <-- RSTACK c1020b0a527e [2024-11-12 09:35:02] debug: zh:ezsp:uart: RSTACK Version: 2 Reason: RESET_SOFTWARE frame: c1020b0a527e [2024-11-12 09:35:02] debug: zh:ezsp:uart: -+- waiting reset success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":4} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":4} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (0,0,0): 00000004 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [004221a850ed2c7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [0142a1a85c2825d8e12b7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (0,1,0): 0142a1a85c2825d8e12b7e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (1): 0142a1a85c2825d8e12b7e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 0080000802306a [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":8,"stackType":2,"stackVersion":27184} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Switching to eszp version 8 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":8} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":8} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (1,1,0): 010001000008 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [7d314321a9542a1dc97f7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [1243a1a9542a1db069feadd37e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (1,2,0): 1243a1a9542a1db069feadd37e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (2): 1243a1a9542a1db069feadd37e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 01800100000802306a [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":8,"stackType":2,"stackVersion":27184} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S = 90 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":56,"value":90} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":56,"value":90} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (2,2,0): 0200015300385a00 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [224021a9072a2de8595e057e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [2340a1a9072a159ab77e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2340a1a9072a159ab77e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (3): 2340a1a9072a159ab77e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 028001530000 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":25,"value":2} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":25,"value":2} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (3,3,0): 0300015300190200 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [334121a9072a0cb05997eb7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [3441a1a9072a15f1287e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3441a1a9072a15f1287e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (4): 3441a1a9072a15f1287e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 038001530000 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_FRAGMENT_DELAY_MS = 50 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":29,"value":50} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":29,"value":50} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (4,4,0): 04000153001d3200 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [444621a9072a08805993d67e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [4546a1a9072a1505697e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (4,5,0): 4546a1a9072a1505697e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (5) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (5): 4546a1a9072a1505697e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 048001530000 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD = 2 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":34,"value":2} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":34,"value":2} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (5,5,0): 0500015300220200 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [554721a9072a37b05980d97e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [5647a1a9072a15af307e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (5,6,0): 5647a1a9072a15af307e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (6) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (6): 5647a1a9072a15af307e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 058001530000 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS = 3 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":42,"value":3} [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":42,"value":3} [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> DATA (6,6,0): 06000153002a0300 [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [664421a9072a3fb1590b7a7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- [6744a1a9072a15803c7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- DATA (6,7,0): 6744a1a9072a15803c7e [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> ACK (7) [2024-11-12 09:35:04] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-12 09:35:04] debug: zh:ezsp:uart: <-- ACK (7): 6744a1a9072a15803c7e [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== Frame: 068001530000 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:04] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 [2024-11-12 09:35:04] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":18,"value":7680} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":18,"value":7680} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (7,7,0): 070001530012001e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [774521a9072a07b2473edc7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [7045a1a9072a15eba37e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (7,0,0): 7045a1a9072a15eba37e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (0) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [8070787e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (0): 7045a1a9072a15eba37e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 078001530000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT = 14 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":19,"value":14} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":19,"value":14} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (0,0,0): 0800015300130e00 [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [004a21a9072a06bc597d3aae7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [014aa1a9072a1512a07e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (0,1,0): 014aa1a9072a1512a07e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (1): 014aa1a9072a1512a07e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 088001530000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_SECURITY_LEVEL = 5 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":13,"value":5} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":13,"value":5} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (1,1,0): 09000153000d0500 [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [7d314b21a9072a7d38b75961387e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [124ba1a9072a15b8f97e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (1,2,0): 124ba1a9072a15b8f97e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (2): 124ba1a9072a15b8f97e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 098001530000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_STACK_PROFILE = 2 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":12,"value":2} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":12,"value":2} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (2,2,0): 0a000153000c0200 [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [224821a9072a19b059deac7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [2348a1a9072a1597f57e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2348a1a9072a1597f57e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (3): 2348a1a9072a1597f57e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 0a8001530000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_FRAGMENT_WINDOW_SIZE = 1 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":28,"value":1} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":28,"value":1} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (3,3,0): 0b000153001c0100 [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [334921a9072a09b35937927e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [3449a1a9072a15fc6a7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3449a1a9072a15fc6a7e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (4): 3449a1a9072a15fc6a7e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 0b8001530000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_PACKET_BUFFER_COUNT = 255 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":1,"value":255} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":1,"value":255} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (4,4,0): 0c0001530001ff00 [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [444e21a9072a144d59db067e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [454ea1a9072a15082b7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (4,5,0): 454ea1a9072a15082b7e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (5) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (5): 454ea1a9072a15082b7e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 0c8001530000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspPolicyId.APP_KEY_REQUEST_POLICY = 96 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":6,"decisionId":96} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":6,"decisionId":96} [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> DATA (5,5,0): 0d00015500066000 [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [554f21a9012a7d33d25964c87e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- [564fa1a9012a1510d27e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- DATA (5,6,0): 564fa1a9012a1510d27e [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> ACK (6) [2024-11-12 09:35:05] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-12 09:35:05] debug: zh:ezsp:uart: <-- ACK (6): 564fa1a9012a1510d27e [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== Frame: 0d8001550000 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-12 09:35:05] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: Set EzspPolicyId.TC_KEY_REQUEST_POLICY = 81 [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":5,"decisionId":81} [2024-11-12 09:35:05] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":5,"decisionId":81} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (6,6,0): 0e00015500055100 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [664c21a9012a10e3597d3a0f7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [674ca1a9012a153fde7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (6,7,0): 674ca1a9012a153fde7e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (7) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (7): 674ca1a9012a153fde7e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 0e8001550000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Set EzspPolicyId.TRUST_CENTER_POLICY = 3 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":0,"decisionId":3} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":0,"decisionId":3} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (7,7,0): 0f00015500000300 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [774d21a9012a15b159662c7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [704da1a9012a1554417e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (7,0,0): 704da1a9012a1554417e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (0) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8070787e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (0): 704da1a9012a1554417e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 0f8001550000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Set EzspValueId.VALUE_END_DEVICE_KEEP_ALIVE_SUPPORT_MODE = 3 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> setValue: {"valueId":63,"value":3} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"setValue","_id_":171,"_isRequest_":true,"valueId":63,"value":3} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (0,0,0): 100001ab003f0003 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [005221a9ff2a2ab25ae4707e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [0152a1a9ff2a154f957e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (0,1,0): 0152a1a9ff2a154f957e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (1): 0152a1a9ff2a154f957e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 108001ab0000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0xab: {"_cls_":"setValue","_id_":171,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Set EzspValueId.VALUE_CCA_THRESHOLD = 0 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> setValue: {"valueId":21,"value":0} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"setValue","_id_":171,"_isRequest_":true,"valueId":21,"value":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (1,1,0): 110001ab00150000 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [7d315321a9ff2a00b2596a7d3a7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [1253a1a9ff2a15e5cc7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (1,2,0): 1253a1a9ff2a15e5cc7e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (2): 1253a1a9ff2a15e5cc7e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 118001ab0000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0xab: {"_cls_":"setValue","_id_":171,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> setConcentrator: {"on":true,"concentratorType":65529,"minTime":10,"maxTime":90,"routeErrorThreshold":4,"deliveryFailureThreshold":3,"maxHops":0} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConcentrator","_id_":16,"_isRequest_":true,"on":true,"concentratorType":65529,"minTime":10,"maxTime":90,"routeErrorThreshold":4,"deliveryFailureThreshold":3,"maxHops":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (2,2,0): 120001100001f9ff0a005a00040300 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [225021a9442a144ba69e4a7faa51914907e87e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [2350a1a9442a15c4ce7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2350a1a9442a15c4ce7e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (3): 2350a1a9442a15c4ce7e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 128001100000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x10: {"_cls_":"setConcentrator","_id_":16,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Set concentrator type: {"_cls_":"setConcentrator","_id_":16,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> setSourceRouteDiscoveryMode: {"mode":1} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"setSourceRouteDiscoveryMode","_id_":90,"_isRequest_":true,"mode":1} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (3,3,0): 1300015a0001 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [335121a90e2a145e887e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [3451a1a90e2a3db2599415397e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3451a1a90e2a3db2599415397e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (4): 3451a1a90e2a3db2599415397e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 1380015a0028000000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x5a: {"_cls_":"setSourceRouteDiscoveryMode","_id_":90,"_isRequest_":false,"remainingTime":40} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> addEndpoint: {"endpoint":1,"profileId":260,"deviceId":48879,"appFlags":0,"inputClusterCount":7,"outputClusterCount":20,"inputClusterList":[0,3,6,10,25,26,768],"outputClusterList":[0,3,4,5,6,8,32,768,1024,1026,1029,1030,1280,2817,2819,2820,1794,4096,64513,64514]} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"addEndpoint","_id_":2,"_isRequest_":true,"endpoint":1,"profileId":260,"deviceId":48879,"appFlags":0,"inputClusterCount":7,"outputClusterCount":20,"inputClusterList":[0,3,6,10,25,26,768],"outputClusterList":[0,3,4,5,6,8,32,768,1024,1026,1029,1030,1280,2817,2819,2820,1794,4096,64513,64514]} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (4,4,0): 1400010200010401efbe0007140000030006000a0019001a0000030000030004000500060008002000000300040204050406040005010b030b040b0207001001fc02fc [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [445621a9562a14b6587bf425ad4192499f4e21abe7ce7d5e8be7c6638afc7d5e3ca7efcddb6f89ffcfdbf5d2698f4627abe8733fa3ee758740934f2d17bae277380c0ffbb919ba1e7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [4556a1a9562a15767d337e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (4,5,0): 4556a1a9562a1576137e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (5) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (5): 4556a1a9562a1576137e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 148001020000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x2: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-12 09:35:06] debug: zh:ezsp:driv: Ezsp adding endpoint: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> addEndpoint: {"endpoint":242,"profileId":41440,"deviceId":97,"appFlags":0,"inputClusterCount":0,"outputClusterCount":1,"inputClusterList":[],"outputClusterList":[33]} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"addEndpoint","_id_":2,"_isRequest_":true,"endpoint":242,"profileId":41440,"deviceId":97,"appFlags":0,"inputClusterCount":0,"outputClusterCount":1,"inputClusterList":[],"outputClusterList":[33]} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (5,5,0): 1500010200f2e0a161000000012100 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [555721a9562ae752f8f54a25aa54b349a19c7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [5657a1a9562a15dc4a7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (5,6,0): 5657a1a9562a15dc4a7e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (6) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (6): 5657a1a9562a15dc4a7e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 158001020000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x2: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-12 09:35:06] debug: zh:ezsp:driv: Ezsp adding endpoint: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Get EzspValueId.VALUE_VERSION_INFO [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> getValue: {"valueId":17} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"getValue","_id_":170,"_isRequest_":true,"valueId":17} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (6,6,0): 160001aa0011 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [665421a9fe2a047fda7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [6754a1a9fe2a15b570954c2fa955389eb77e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (6,7,0): 6754a1a9fe2a15b570954c2fa955389eb77e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (7) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (7): 6754a1a9fe2a15b570954c2fa955389eb77e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 168001aa0000072901060a0300aa [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0xaa: {"_cls_":"getValue","_id_":170,"_isRequest_":false,"status":0,"value":{"type":"Buffer","data":[41,1,6,10,3,0,170]}} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Got EzspValueId.VALUE_VERSION_INFO = ) � [2024-11-12 09:35:06] debug: zh:ezsp:driv: EmberZNet version: 6.10.3.0 build 297 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> networkInit: undefined [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"networkInit","_id_":23,"_isRequest_":true} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (7,7,0): 1700011700 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [775521a9432a0ae87e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [7055a5a9432a15b94c7e0055b1a94d2a8528c97e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (7,0,0): 7055a5a9432a15b94c7e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (0) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8070787e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (0): 7055a5a9432a15b94c7e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 178401170000 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x17: {"_cls_":"networkInit","_id_":23,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (0,0,0): 0055b1a94d2a8528c97e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- ACK (0): 0055b1a94d2a8528c97e [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== Frame: 179001190090 [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: <== 0x19: {"_cls_":"stackStatusHandler","_id_":25,"_isRequest_":false,"status":144} [2024-11-12 09:35:06] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: Network init result: {"_cls_":"networkInit","_id_":23,"_isRequest_":false,"status":0} [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> getNetworkParameters: undefined [2024-11-12 09:35:06] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true} [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> DATA (0,1,0): 1800012800 [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> [015a21a97c2ab2c77e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- [7d315aa1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8e367c7e] [2024-11-12 09:35:06] debug: zh:ezsp:uart: <-- DATA (1,1,0): 115aa1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8e367c7e [2024-11-12 09:35:06] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- ACK (1): 115aa1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8e367c7e [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: <== Frame: 18800128000001b42082fad35f1f7e95b7050b0000000000f8ff07 [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}} [2024-11-12 09:35:07] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:07] debug: zh:ezsp:driv: Current Node type: 1, Network parameters: EmberNetworkParameters: {"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680} [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: ==> getNetworkParameters: undefined [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true} [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> DATA (1,2,0): 1900012800 [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> [125b21a97c2accf27e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- [225ba1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8ef5a07e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- DATA (2,2,0): 225ba1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8ef5a07e [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- ACK (2): 225ba1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8ef5a07e [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: <== Frame: 19800128000001b42082fad35f1f7e95b7050b0000000000f8ff07 [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}} [2024-11-12 09:35:07] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:07] debug: zh:ezsp:driv: Current Node type: 1, Network parameters: EmberNetworkParameters: {"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680} [2024-11-12 09:35:07] debug: zh:ezsp:driv: Backuped network parameters: [object Object] [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: ==> getKey: {"keyType":3} [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: ==> {"_cls_":"getKey","_id_":106,"_isRequest_":true,"keyType":3} [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> DATA (2,3,0): 1a00016a0003 [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> [235821a93e2a16c4f67e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- [3358a1a93e2a15b15997e93b2e8b6e6b3bf43b7acc9f9d259c9662a9b37d5e3f] [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- [a7ebcdde6f8fffc7dbd5d26983727e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- DATA (3,3,0): 3358a1a93e2a15b15997e93b2e8b6e6b3bf43b7acc9f9d259c9662a9b37e3fa7ebcdde6f8fffc7dbd5d26983727e [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:07] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:07] debug: zh:ezsp:uart: <-- ACK (3): 3358a1a93e2a15b15997e93b2e8b6e6b3bf43b7acc9f9d259c9662a9b37e3fa7ebcdde6f8fffc7dbd5d26983727e [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: <== Frame: 1a80016a0000030003a31e84defc22a7ba1cd12151faae615001204f0000000000000000000000000000 [2024-11-12 09:35:07] debug: zh:ezsp:ezsp: <== 0x6a: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"key":{"contents":[163,30,132,222,252,34,167,186,28,209,33,81,250,174,97,80]},"outgoingFrameCounter":5185537,"sequenceNumber":0,"bitmask":3,"type":3,"incomingFrameCounter":0,"partnerEUI64":{"type":"Buffer","data":[0,0,0,0,0,0,0,0]}}} [2024-11-12 09:35:07] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:07] error: zh:ezsp:driv: Configuration is not consistent with adapter backup! [2024-11-12 09:35:07] error: zh:ezsp:driv: - PAN ID: configured=6754, adapter=46997, backup=46997 [2024-11-12 09:35:07] error: zh:ezsp:driv: - Extended PAN ID: configured=dddddddddddddddd, adapter=b42082fad35f1f7e, backup=b42082fad35f1f7e [2024-11-12 09:35:07] error: zh:ezsp:driv: - Channel: configured=11, adapter=11, backup=11 [2024-11-12 09:35:07] error: zh:ezsp:driv: - Network key: configured=01030507090b0d0f00020406080a0c0d, adapter=a31e84defc22a7ba1cd12151faae6150, backup=a31e84defc22a7ba1cd12151faae6150 [2024-11-12 09:35:07] error: zh:ezsp:driv: Please update configuration to prevent further issues. [2024-11-12 09:35:07] error: zh:ezsp:driv: If you wish to re-commission your network, please remove coordinator backup. [2024-11-12 09:35:07] error: zh:ezsp:driv: Re-commissioning your network will require re-pairing of all devices! [2024-11-12 09:35:07] error: z2m: Error while starting zigbee-herdsman [2024-11-12 09:35:07] error: z2m: Failed to start zigbee [2024-11-12 09:35:07] error: z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions [2024-11-12 09:35:07] error: z2m: Exiting... [2024-11-12 09:35:07] error: z2m: Error: startup failed - configuration-adapter mismatch - see logs above for more information at Driver.needsToBeRestore (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/driver.ts:985:19) at Driver.startup (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/driver.ts:244:29) at EZSPAdapter.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/adapter/ezspAdapter.ts:152:16) at Controller.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/controller/controller.ts:137:29) at Zigbee.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/lib/zigbee.ts:69:27) at Controller.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/lib/controller.ts:161:27) at start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/index.js:154:5) > zigbee2mqtt@1.41.0 start > node index.js Starting Zigbee2MQTT without watchdog. [2024-11-12 09:35:42] info: z2m: Logging to console, file (filename: log.log) [2024-11-12 09:35:42] error: z2m: Failed to load external converter file '/var/www/html/plugins/z2m/core/class/../config/converters/Danfoss/icon.js' (Cannot find module 'zigbee-herdsman-converters/lib/extend' Require stack: - /var/www/html/plugins/z2m/resources/zigbee2mqtt/dist/util/utils.js - /var/www/html/plugins/z2m/resources/zigbee2mqtt/dist/util/settings.js - /var/www/html/plugins/z2m/resources/zigbee2mqtt/index.js) [2024-11-12 09:35:42] error: z2m: Probably there is a syntax error in the file or the external converter is not compatible with the current Zigbee2MQTT version [2024-11-12 09:35:42] error: z2m: Note that external converters are not meant for long term usage, it's meant for local testing after which a pull request should be created to add out-of-the-box support for the device [2024-11-12 09:35:42] debug: z2m: Loaded state from file /var/www/html/plugins/z2m/data/state.json [2024-11-12 09:35:42] info: z2m: Starting Zigbee2MQTT version 1.41.0 (commit #cc31cea) [2024-11-12 09:35:42] debug: z2m: sd-notify loaded [2024-11-12 09:35:42] info: z2m: Starting zigbee-herdsman (2.1.7) [2024-11-12 09:35:42] debug: 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\":{\"path\":\"/dev/ttyLuna-Zigbee\",\"adapter\":\"ezsp\"},\"adapter\":{\"disableLED\":false}}"' [2024-11-12 09:35:44] debug: zh:ezsp: Adapter concurrent: 8 [2024-11-12 09:35:44] debug: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"path":"/dev/ttyLuna-Zigbee","adapter":"ezsp"},"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"}' [2024-11-12 09:35:44] warning: zh:ezsp: 'ezsp' driver is deprecated and will only remain to provide support for older firmware (pre 7.4.x). Migration to 'ember' is recommended. If using Zigbee2MQTT see https://github.com/Koenkk/zigbee2mqtt/discussions/21462 [2024-11-12 09:35:44] debug: zh:ezsp:uart: RTS/CTS config is off, enabling software flow control. [2024-11-12 09:35:44] debug: zh:ezsp:uart: Opening SerialPort with {"path":"/dev/ttyLuna-Zigbee","baudRate":115200,"rtscts":false,"autoOpen":false,"parity":"none","stopBits":1,"xon":true,"xoff":true} [2024-11-12 09:35:44] debug: zh:ezsp:uart: Serialport opened [2024-11-12 09:35:44] debug: zh:ezsp:uart: Uart reseting [2024-11-12 09:35:44] debug: zh:ezsp:uart: --> Write reset [2024-11-12 09:35:44] debug: zh:ezsp:uart: --> [1ac038bc7e] [2024-11-12 09:35:44] debug: zh:ezsp:uart: -?- waiting reset [2024-11-12 09:35:45] debug: zh:ezsp:uart: <-- [c1020b0a527e] [2024-11-12 09:35:45] debug: zh:ezsp:uart: <-- RSTACK c1020b0a527e [2024-11-12 09:35:45] debug: zh:ezsp:uart: RSTACK Version: 2 Reason: RESET_SOFTWARE frame: c1020b0a527e [2024-11-12 09:35:45] debug: zh:ezsp:uart: -+- waiting reset success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":4} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":4} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (0,0,0): 00000004 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [004221a850ed2c7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [0142a1a85c2825d8e12b7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (0,1,0): 0142a1a85c2825d8e12b7e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (1): 0142a1a85c2825d8e12b7e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 0080000802306a [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":8,"stackType":2,"stackVersion":27184} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Switching to eszp version 8 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":8} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":8} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (1,1,0): 010001000008 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [7d314321a9542a1dc97f7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [1243a1a9542a1db069feadd37e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (1,2,0): 1243a1a9542a1db069feadd37e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (2): 1243a1a9542a1db069feadd37e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 01800100000802306a [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":8,"stackType":2,"stackVersion":27184} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S = 90 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":56,"value":90} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":56,"value":90} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (2,2,0): 0200015300385a00 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [224021a9072a2de8595e057e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [2340a1a9072a159ab77e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2340a1a9072a159ab77e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (3): 2340a1a9072a159ab77e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 028001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":25,"value":2} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":25,"value":2} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (3,3,0): 0300015300190200 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [334121a9072a0cb05997eb7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [3441a1a9072a15f1287e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3441a1a9072a15f1287e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (4): 3441a1a9072a15f1287e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 038001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_FRAGMENT_DELAY_MS = 50 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":29,"value":50} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":29,"value":50} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (4,4,0): 04000153001d3200 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [444621a9072a08805993d67e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [4546a1a9072a1505697e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (4,5,0): 4546a1a9072a1505697e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (5) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (5): 4546a1a9072a1505697e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 048001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD = 2 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":34,"value":2} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":34,"value":2} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (5,5,0): 0500015300220200 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [554721a9072a37b05980d97e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [5647a1a9072a15af307e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (5,6,0): 5647a1a9072a15af307e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (6) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (6): 5647a1a9072a15af307e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 058001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS = 3 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":42,"value":3} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":42,"value":3} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (6,6,0): 06000153002a0300 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [664421a9072a3fb1590b7a7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [6744a1a9072a15803c7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (6,7,0): 6744a1a9072a15803c7e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (7) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (7): 6744a1a9072a15803c7e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 068001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":18,"value":7680} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":18,"value":7680} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (7,7,0): 070001530012001e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [774521a9072a07b2473edc7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [7045a1a9072a15eba37e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (7,0,0): 7045a1a9072a15eba37e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (0) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [8070787e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (0): 7045a1a9072a15eba37e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 078001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT = 14 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":19,"value":14} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":19,"value":14} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (0,0,0): 0800015300130e00 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [004a21a9072a06bc597d3aae7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [014aa1a9072a1512a07e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (0,1,0): 014aa1a9072a1512a07e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (1): 014aa1a9072a1512a07e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 088001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_SECURITY_LEVEL = 5 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":13,"value":5} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":13,"value":5} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (1,1,0): 09000153000d0500 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [7d314b21a9072a7d38b75961387e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [124ba1a9072a15b8f97e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (1,2,0): 124ba1a9072a15b8f97e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (2): 124ba1a9072a15b8f97e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 098001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_STACK_PROFILE = 2 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":12,"value":2} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":12,"value":2} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (2,2,0): 0a000153000c0200 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [224821a9072a19b059deac7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- [2348a1a9072a1597f57e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2348a1a9072a1597f57e [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: <-- ACK (3): 2348a1a9072a1597f57e [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== Frame: 0a8001530000 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:47] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_FRAGMENT_WINDOW_SIZE = 1 [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":28,"value":1} [2024-11-12 09:35:47] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":28,"value":1} [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> DATA (3,3,0): 0b000153001c0100 [2024-11-12 09:35:47] debug: zh:ezsp:uart: --> [334921a9072a09b35937927e] [2024-11-12 09:35:47] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [3449a1a9072a15fc6a7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3449a1a9072a15fc6a7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (4): 3449a1a9072a15fc6a7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 0b8001530000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_PACKET_BUFFER_COUNT = 255 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":1,"value":255} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":1,"value":255} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (4,4,0): 0c0001530001ff00 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [444e21a9072a144d59db067e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [454ea1a9072a15082b7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (4,5,0): 454ea1a9072a15082b7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (5) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (5): 454ea1a9072a15082b7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 0c8001530000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set EzspPolicyId.APP_KEY_REQUEST_POLICY = 96 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":6,"decisionId":96} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":6,"decisionId":96} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (5,5,0): 0d00015500066000 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [554f21a9012a7d33d25964c87e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [564fa1a9012a1510d27e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (5,6,0): 564fa1a9012a1510d27e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (6) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (6): 564fa1a9012a1510d27e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 0d8001550000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set EzspPolicyId.TC_KEY_REQUEST_POLICY = 81 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":5,"decisionId":81} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":5,"decisionId":81} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (6,6,0): 0e00015500055100 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [664c21a9012a10e3597d3a0f7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [674ca1a9012a153fde7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (6,7,0): 674ca1a9012a153fde7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (7) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (7): 674ca1a9012a153fde7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 0e8001550000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set EzspPolicyId.TRUST_CENTER_POLICY = 3 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":0,"decisionId":3} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":0,"decisionId":3} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (7,7,0): 0f00015500000300 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [774d21a9012a15b159662c7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [704da1a9012a1554417e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (7,0,0): 704da1a9012a1554417e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (0) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8070787e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (0): 704da1a9012a1554417e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 0f8001550000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set EzspValueId.VALUE_END_DEVICE_KEEP_ALIVE_SUPPORT_MODE = 3 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setValue: {"valueId":63,"value":3} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setValue","_id_":171,"_isRequest_":true,"valueId":63,"value":3} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (0,0,0): 100001ab003f0003 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [005221a9ff2a2ab25ae4707e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [0152a1a9ff2a154f957e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (0,1,0): 0152a1a9ff2a154f957e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (1): 0152a1a9ff2a154f957e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 108001ab0000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0xab: {"_cls_":"setValue","_id_":171,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set EzspValueId.VALUE_CCA_THRESHOLD = 0 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setValue: {"valueId":21,"value":0} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setValue","_id_":171,"_isRequest_":true,"valueId":21,"value":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (1,1,0): 110001ab00150000 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [7d315321a9ff2a00b2596a7d3a7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [1253a1a9ff2a15e5cc7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (1,2,0): 1253a1a9ff2a15e5cc7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (2): 1253a1a9ff2a15e5cc7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 118001ab0000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0xab: {"_cls_":"setValue","_id_":171,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setConcentrator: {"on":true,"concentratorType":65529,"minTime":10,"maxTime":90,"routeErrorThreshold":4,"deliveryFailureThreshold":3,"maxHops":0} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConcentrator","_id_":16,"_isRequest_":true,"on":true,"concentratorType":65529,"minTime":10,"maxTime":90,"routeErrorThreshold":4,"deliveryFailureThreshold":3,"maxHops":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (2,2,0): 120001100001f9ff0a005a00040300 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [225021a9442a144ba69e4a7faa51914907e87e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [2350a1a9442a15c4ce7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2350a1a9442a15c4ce7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (3): 2350a1a9442a15c4ce7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 128001100000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x10: {"_cls_":"setConcentrator","_id_":16,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Set concentrator type: {"_cls_":"setConcentrator","_id_":16,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> setSourceRouteDiscoveryMode: {"mode":1} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"setSourceRouteDiscoveryMode","_id_":90,"_isRequest_":true,"mode":1} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (3,3,0): 1300015a0001 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [335121a90e2a145e887e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [3451a1a90e2a3db2599415397e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3451a1a90e2a3db2599415397e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (4): 3451a1a90e2a3db2599415397e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 1380015a0028000000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x5a: {"_cls_":"setSourceRouteDiscoveryMode","_id_":90,"_isRequest_":false,"remainingTime":40} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> addEndpoint: {"endpoint":1,"profileId":260,"deviceId":48879,"appFlags":0,"inputClusterCount":7,"outputClusterCount":20,"inputClusterList":[0,3,6,10,25,26,768],"outputClusterList":[0,3,4,5,6,8,32,768,1024,1026,1029,1030,1280,2817,2819,2820,1794,4096,64513,64514]} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"addEndpoint","_id_":2,"_isRequest_":true,"endpoint":1,"profileId":260,"deviceId":48879,"appFlags":0,"inputClusterCount":7,"outputClusterCount":20,"inputClusterList":[0,3,6,10,25,26,768],"outputClusterList":[0,3,4,5,6,8,32,768,1024,1026,1029,1030,1280,2817,2819,2820,1794,4096,64513,64514]} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (4,4,0): 1400010200010401efbe0007140000030006000a0019001a0000030000030004000500060008002000000300040204050406040005010b030b040b0207001001fc02fc [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [445621a9562a14b6587bf425ad4192499f4e21abe7ce7d5e8be7c6638afc7d5e3ca7efcddb6f89ffcfdbf5d2698f4627abe8733fa3ee758740934f2d17bae277380c0ffbb919ba1e7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [4556a1a9562a15767d337e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (4,5,0): 4556a1a9562a1576137e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (5) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (5): 4556a1a9562a1576137e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 148001020000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x2: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-12 09:35:48] debug: zh:ezsp:driv: Ezsp adding endpoint: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> addEndpoint: {"endpoint":242,"profileId":41440,"deviceId":97,"appFlags":0,"inputClusterCount":0,"outputClusterCount":1,"inputClusterList":[],"outputClusterList":[33]} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"addEndpoint","_id_":2,"_isRequest_":true,"endpoint":242,"profileId":41440,"deviceId":97,"appFlags":0,"inputClusterCount":0,"outputClusterCount":1,"inputClusterList":[],"outputClusterList":[33]} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (5,5,0): 1500010200f2e0a161000000012100 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [555721a9562ae752f8f54a25aa54b349a19c7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [5657a1a9562a15dc4a7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (5,6,0): 5657a1a9562a15dc4a7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (6) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (6): 5657a1a9562a15dc4a7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 158001020000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x2: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-12 09:35:48] debug: zh:ezsp:driv: Ezsp adding endpoint: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Get EzspValueId.VALUE_VERSION_INFO [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> getValue: {"valueId":17} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"getValue","_id_":170,"_isRequest_":true,"valueId":17} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (6,6,0): 160001aa0011 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [665421a9fe2a047fda7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [6754a1a9fe2a15b570954c2fa955389eb77e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (6,7,0): 6754a1a9fe2a15b570954c2fa955389eb77e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (7) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (7): 6754a1a9fe2a15b570954c2fa955389eb77e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 168001aa0000072901060a0300aa [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0xaa: {"_cls_":"getValue","_id_":170,"_isRequest_":false,"status":0,"value":{"type":"Buffer","data":[41,1,6,10,3,0,170]}} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Got EzspValueId.VALUE_VERSION_INFO = ) � [2024-11-12 09:35:48] debug: zh:ezsp:driv: EmberZNet version: 6.10.3.0 build 297 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> networkInit: undefined [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"networkInit","_id_":23,"_isRequest_":true} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (7,7,0): 1700011700 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [775521a9432a0ae87e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [7055a5a9432a15b94c7e0055b1a94d2a8528c97e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (7,0,0): 7055a5a9432a15b94c7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (0) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8070787e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (0): 7055a5a9432a15b94c7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 178401170000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x17: {"_cls_":"networkInit","_id_":23,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (0,0,0): 0055b1a94d2a8528c97e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (1) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8160597e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (0): 0055b1a94d2a8528c97e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 179001190090 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x19: {"_cls_":"stackStatusHandler","_id_":25,"_isRequest_":false,"status":144} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: Network init result: {"_cls_":"networkInit","_id_":23,"_isRequest_":false,"status":0} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> getNetworkParameters: undefined [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (0,1,0): 1800012800 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [015a21a97c2ab2c77e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [7d315aa1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8e367c7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (1,1,0): 115aa1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8e367c7e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (2) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (1): 115aa1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8e367c7e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 18800128000001b42082fad35f1f7e95b7050b0000000000f8ff07 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-12 09:35:48] debug: zh:ezsp:driv: Current Node type: 1, Network parameters: EmberNetworkParameters: {"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> getNetworkParameters: undefined [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (1,2,0): 1900012800 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [125b21a97c2accf27e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [225ba1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8ef5a07e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (2,2,0): 225ba1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8ef5a07e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (3) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (2): 225ba1a97c2a15b3edb4c8df790a8d3709f922a0edce678bfd3e9c8ef5a07e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 19800128000001b42082fad35f1f7e95b7050b0000000000f8ff07 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-12 09:35:48] debug: zh:ezsp:driv: Current Node type: 1, Network parameters: EmberNetworkParameters: {"extendedPanId":[180,32,130,250,211,95,31,126],"panId":46997,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680} [2024-11-12 09:35:48] debug: zh:ezsp:driv: Backuped network parameters: [object Object] [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> getKey: {"keyType":3} [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: ==> {"_cls_":"getKey","_id_":106,"_isRequest_":true,"keyType":3} [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> DATA (2,3,0): 1a00016a0003 [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [235821a93e2a16c4f67e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [3358a1a93e2a15b15997e93b2e8b6e6b3bf43b7acc9f9d259c9662b9b37d5e3f] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- [a7ebcdde6f8fffc7dbd5d269a0787e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- DATA (3,3,0): 3358a1a93e2a15b15997e93b2e8b6e6b3bf43b7acc9f9d259c9662b9b37e3fa7ebcdde6f8fffc7dbd5d269a0787e [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> ACK (4) [2024-11-12 09:35:48] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-12 09:35:48] debug: zh:ezsp:uart: <-- ACK (3): 3358a1a93e2a15b15997e93b2e8b6e6b3bf43b7acc9f9d259c9662b9b37e3fa7ebcdde6f8fffc7dbd5d269a0787e [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== Frame: 1a80016a0000030003a31e84defc22a7ba1cd12151faae615001304f0000000000000000000000000000 [2024-11-12 09:35:48] debug: zh:ezsp:ezsp: <== 0x6a: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"key":{"contents":[163,30,132,222,252,34,167,186,28,209,33,81,250,174,97,80]},"outgoingFrameCounter":5189633,"sequenceNumber":0,"bitmask":3,"type":3,"incomingFrameCounter":0,"partnerEUI64":{"type":"Buffer","data":[0,0,0,0,0,0,0,0]}}} [2024-11-12 09:35:48] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-12 09:35:48] error: zh:ezsp:driv: Configuration is not consistent with adapter backup! [2024-11-12 09:35:48] error: zh:ezsp:driv: - PAN ID: configured=6754, adapter=46997, backup=46997 [2024-11-12 09:35:48] error: zh:ezsp:driv: - Extended PAN ID: configured=dddddddddddddddd, adapter=b42082fad35f1f7e, backup=b42082fad35f1f7e [2024-11-12 09:35:48] error: zh:ezsp:driv: - Channel: configured=11, adapter=11, backup=11 [2024-11-12 09:35:48] error: zh:ezsp:driv: - Network key: configured=01030507090b0d0f00020406080a0c0d, adapter=a31e84defc22a7ba1cd12151faae6150, backup=a31e84defc22a7ba1cd12151faae6150 [2024-11-12 09:35:48] error: zh:ezsp:driv: Please update configuration to prevent further issues. [2024-11-12 09:35:48] error: zh:ezsp:driv: If you wish to re-commission your network, please remove coordinator backup. [2024-11-12 09:35:48] error: zh:ezsp:driv: Re-commissioning your network will require re-pairing of all devices! [2024-11-12 09:35:48] error: z2m: Error while starting zigbee-herdsman [2024-11-12 09:35:48] error: z2m: Failed to start zigbee [2024-11-12 09:35:48] error: z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions [2024-11-12 09:35:48] error: z2m: Exiting... [2024-11-12 09:35:48] error: z2m: Error: startup failed - configuration-adapter mismatch - see logs above for more information at Driver.needsToBeRestore (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/driver.ts:985:19) at Driver.startup (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/driver.ts:244:29) at EZSPAdapter.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/adapter/ezspAdapter.ts:152:16) at Controller.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/node_modules/zigbee-herdsman/src/controller/controller.ts:137:29) at Zigbee.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/lib/zigbee.ts:69:27) at Controller.start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/lib/controller.ts:161:27) at start (/var/www/html/plugins/z2m/resources/zigbee2mqtt/index.js:154:5)