root@iobroker:/opt/zigbee2mqtt# sudo service zigbee2mqtt stop root@iobroker:/opt/zigbee2mqtt# npm start > zigbee2mqtt@1.40.1 start > node index.js Starting Zigbee2MQTT without watchdog. [2024-11-19 10:28:48] info: z2m: Logging to console, file (filename: log.log) [2024-11-19 10:28:48] debug: z2m: Loaded state from file /opt/zigbee2mqtt/data/state.json [2024-11-19 10:28:48] info: z2m: Starting Zigbee2MQTT version 1.40.1 (commit #403d3c0) [2024-11-19 10:28:48] info: z2m: Starting zigbee-herdsman (0.57.3) [2024-11-19 10:28:48] debug: z2m: Using zigbee-herdsman with settings: '"{\"network\":{\"panID\":1748,\"extendedPanID\":[106,111,32,69,142,92,130,83],\"channelList\":[11],\"networkKey\":\"HIDDEN\"},\"databasePath\":\"/opt/zigbee2mqtt/data/database.db\",\"databaseBackupPath\":\"/opt/zigbee2mqtt/data/database.db.backup\",\"backupPath\":\"/opt/zigbee2mqtt/data/coordinator_backup.json\",\"serialPort\":{\"baudRate\":115200,\"path\":\"tcp://192.168.0.33:6638\",\"adapter\":\"ezsp\"},\"adapter\":{\"concurrent\":null,\"delay\":null,\"disableLED\":false}}"' [2024-11-19 10:28:48] debug: zh:ezsp: Adapter concurrent: 8 [2024-11-19 10:28:48] debug: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":1748,"extendedPanID":[106,111,32,69,142,92,130,83],"channelList":[11]},"serialPort":{"baudRate":115200,"path":"tcp://192.168.0.33:6638","adapter":"ezsp"},"adapter":{"disableLED":false,"concurrent":null,"delay":null},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json"}' [2024-11-19 10:28:48] warning: zh:ezsp: Deprecated driver 'ezsp' currently in use, 'ember' will become the officially supported EmberZNet driver in next release. If using Zigbee2MQTT see https://github.com/Koenkk/zigbee2mqtt/discussions/21462 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Opening TCP socket with 192.168.0.33:6638 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Socket connected [2024-11-19 10:28:48] debug: zh:ezsp:uart: Socket ready [2024-11-19 10:28:48] debug: zh:ezsp:uart: Uart reseting [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> Write reset [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [1ac038bc7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: -?- waiting reset [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- [2203b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67d5e77e2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e3203b1a96b2a7d334ea6944a3aaa5592499c4e275412ce67e70e7e2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e3a03b1a96b2a7d334ea6944a3aaa5592499c4e275412ce67ff357e2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e3a03b1a96b2a7d334ea6944a3aaa5592499c4e275412ce67ff357ec20251a8bd7e4203b1a96b2a164fa6904b23aa546d499d4e273d12ce67ebf57e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (2,2,0): 2203b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67d5e77e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected DATA packet sequence 2 | 0: reject condition [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [a0547d3a7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (2,2,1): 2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected DATA packet sequence 2 | 0: packet replay [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- ACK (2): 2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Skipping the packet as repeated (3) [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (3,2,0): 3203b1a96b2a134ea6944a3aaa5592499c4e275412ce67e70e7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- ACK (2): 3203b1a96b2a134ea6944a3aaa5592499c4e275412ce67e70e7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 [2024-11-19 10:28:48] debug: zh:ezsp:ezsp: <== Frame: 4190013f0006fcff00001f00000000000000ffff0000 [2024-11-19 10:28:48] debug: zh:ezsp:ezsp: <== 0x1: {"_cls_":"getLibraryStatus","_id_":1,"_isRequest_":false,"status":63} [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (2,2,1): 2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected DATA packet sequence 2 | 4: packet replay [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- ACK (2): 2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Skipping the packet as repeated (3) [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (3,2,1): 3a03b1a96b2a134ea6944a3aaa5592499c4e275412ce67ff357e [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- ACK (2): 3a03b1a96b2a134ea6944a3aaa5592499c4e275412ce67ff357e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Skipping the packet as repeated (4) [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (2,2,1): 2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected DATA packet sequence 2 | 4: packet replay [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- ACK (2): 2a03b1a96b2a164fa674eb04aaa76d499db3d8e8e5ce67cddc7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Skipping the packet as repeated (3) [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (3,2,1): 3a03b1a96b2a134ea6944a3aaa5592499c4e275412ce67ff357e [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- ACK (2): 3a03b1a96b2a134ea6944a3aaa5592499c4e275412ce67ff357e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 [2024-11-19 10:28:48] debug: zh:ezsp:uart: Skipping the packet as repeated (4) [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- Error c20251a8bd7e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Uart reseting [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> Write reset [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [1ac038bc7e] [2024-11-19 10:28:48] debug: zh:ezsp:uart: -?- waiting reset [2024-11-19 10:28:48] debug: zh:ezsp:uart: <-- DATA (4,2,0): 4203b1a96b2a164fa6904b23aa546d499d4e273d12ce67ebf57e [2024-11-19 10:28:48] debug: zh:ezsp:uart: Unexpected DATA packet sequence 4 | 0: reject condition [2024-11-19 10:28:48] debug: zh:ezsp:uart: --> [a0547d3a7e] [2024-11-19 10:28:49] debug: zh:ezsp:uart: <-- [c1020b0a527e] [2024-11-19 10:28:49] debug: zh:ezsp:uart: <-- RSTACK c1020b0a527e [2024-11-19 10:28:49] debug: zh:ezsp:uart: RSTACK Version: 2 Reason: RESET_SOFTWARE frame: c1020b0a527e [2024-11-19 10:28:49] debug: zh:ezsp:uart: -+- waiting reset success [2024-11-19 10:28:49] debug: zh:ezsp:uart: -+- waiting reset success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":4} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":4} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (0,0,0): 00000004 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [004221a850ed2c7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [0142a1a8582805c1ae247e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (0,1,0): 0142a1a8582805c1ae247e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8160597e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (1): 0142a1a8582805c1ae247e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0080000c021073 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":12,"stackType":2,"stackVersion":29456} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Switching to eszp version 12 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":12} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":12} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (1,1,0): 01000100000c [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [7d314321a9542a1989fb7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [1243a1a9542a19b049e7e2dc7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (1,2,0): 1243a1a9542a19b049e7e2dc7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (2) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (2): 1243a1a9542a19b049e7e2dc7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 01800100000c021073 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x0: {"_cls_":"version","_id_":0,"_isRequest_":false,"protocolVersion":12,"stackType":2,"stackVersion":29456} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_TC_REJOINS_USING_WELL_KNOWN_KEY_TIMEOUT_S = 90 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":56,"value":90} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":56,"value":90} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (2,2,0): 0200015300385a00 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [224021a9072a2de8595e057e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [2340a1a9072a159ab77e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2340a1a9072a159ab77e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (3): 2340a1a9072a159ab77e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 028001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":25,"value":2} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":25,"value":2} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (3,3,0): 0300015300190200 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [334121a9072a0cb05997eb7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [3441a1a9072a15f1287e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3441a1a9072a15f1287e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (4): 3441a1a9072a15f1287e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 038001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_FRAGMENT_DELAY_MS = 50 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":29,"value":50} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":29,"value":50} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (4,4,0): 04000153001d3200 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [444621a9072a08805993d67e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [4546a1a9072a1505697e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (4,5,0): 4546a1a9072a1505697e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (5) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (5): 4546a1a9072a1505697e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 048001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_PAN_ID_CONFLICT_REPORT_THRESHOLD = 2 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":34,"value":2} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":34,"value":2} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (5,5,0): 0500015300220200 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [554721a9072a37b05980d97e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [5647a1a9072a15af307e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (5,6,0): 5647a1a9072a15af307e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (6) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (6): 5647a1a9072a15af307e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 058001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_APPLICATION_ZDO_FLAGS = 3 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":42,"value":3} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":42,"value":3} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (6,6,0): 06000153002a0300 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [664421a9072a3fb1590b7a7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [6744a1a9072a15803c7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (6,7,0): 6744a1a9072a15803c7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (7) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (7): 6744a1a9072a15803c7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 068001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":18,"value":7680} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":18,"value":7680} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (7,7,0): 070001530012001e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [774521a9072a07b2473edc7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [7045a1a9072a15eba37e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (7,0,0): 7045a1a9072a15eba37e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (0) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8070787e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (0): 7045a1a9072a15eba37e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 078001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_END_DEVICE_POLL_TIMEOUT = 14 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":19,"value":14} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":19,"value":14} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (0,0,0): 0800015300130e00 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [004a21a9072a06bc597d3aae7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [014aa1a9072a1512a07e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (0,1,0): 014aa1a9072a1512a07e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8160597e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (1): 014aa1a9072a1512a07e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 088001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_SECURITY_LEVEL = 5 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":13,"value":5} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":13,"value":5} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (1,1,0): 09000153000d0500 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [7d314b21a9072a7d38b75961387e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [124ba1a9072a15b8f97e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (1,2,0): 124ba1a9072a15b8f97e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (2) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (2): 124ba1a9072a15b8f97e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 098001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_STACK_PROFILE = 2 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":12,"value":2} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":12,"value":2} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (2,2,0): 0a000153000c0200 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [224821a9072a19b059deac7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [2348a1a9072a1597f57e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2348a1a9072a1597f57e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (3): 2348a1a9072a1597f57e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0a8001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspConfigId.CONFIG_FRAGMENT_WINDOW_SIZE = 1 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConfigurationValue: {"configId":28,"value":1} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":true,"configId":28,"value":1} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (3,3,0): 0b000153001c0100 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [334921a9072a09b35937927e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [3449a1a9072a15fc6a7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3449a1a9072a15fc6a7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (4): 3449a1a9072a15fc6a7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0b8001530000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x53: {"_cls_":"setConfigurationValue","_id_":83,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspPolicyId.APP_KEY_REQUEST_POLICY = 96 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":6,"decisionId":96} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":6,"decisionId":96} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (4,4,0): 0c00015500066000 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [444e21a9012a7d33d2599bc67e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [454ea1a9012a15ba8b7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (4,5,0): 454ea1a9012a15ba8b7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (5) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (5): 454ea1a9012a15ba8b7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0c8001550000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspPolicyId.TC_KEY_REQUEST_POLICY = 81 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":5,"decisionId":81} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":5,"decisionId":81} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (5,5,0): 0d00015500055100 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [554f21a9012a10e3590b3c7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [564fa1a9012a1510d27e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (5,6,0): 564fa1a9012a1510d27e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (6) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (6): 564fa1a9012a1510d27e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0d8001550000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspPolicyId.TRUST_CENTER_POLICY = 3 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setPolicy: {"policyId":0,"decisionId":3} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setPolicy","_id_":85,"_isRequest_":true,"policyId":0,"decisionId":3} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (6,6,0): 0e00015500000300 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [664c21a9012a15b15999227e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [674ca1a9012a153fde7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (6,7,0): 674ca1a9012a153fde7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (7) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (7): 674ca1a9012a153fde7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0e8001550000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x55: {"_cls_":"setPolicy","_id_":85,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspValueId.VALUE_END_DEVICE_KEEP_ALIVE_SUPPORT_MODE = 3 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setValue: {"valueId":63,"value":3} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setValue","_id_":171,"_isRequest_":true,"valueId":63,"value":3} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (7,7,0): 0f0001ab003f0003 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [774d21a9ff2a2ab25a7d3a767e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [704da1a9ff2a15ac127e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (7,0,0): 704da1a9ff2a15ac127e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (0) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8070787e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (0): 704da1a9ff2a15ac127e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 0f8001ab0000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0xab: {"_cls_":"setValue","_id_":171,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set EzspValueId.VALUE_CCA_THRESHOLD = 0 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setValue: {"valueId":21,"value":0} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setValue","_id_":171,"_isRequest_":true,"valueId":21,"value":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (0,0,0): 100001ab00150000 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [005221a9ff2a00b25995147e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [0152a1a9ff2a154f957e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (0,1,0): 0152a1a9ff2a154f957e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8160597e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (1): 0152a1a9ff2a154f957e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 108001ab0000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0xab: {"_cls_":"setValue","_id_":171,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setConcentrator: {"on":true,"concentratorType":65529,"minTime":10,"maxTime":90,"routeErrorThreshold":4,"deliveryFailureThreshold":3,"maxHops":0} [2024-11-19 10:28:52] 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-19 10:28:52] debug: zh:ezsp:uart: --> DATA (1,1,0): 110001100001f9ff0a005a00040300 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [7d315321a9442a144ba69e4a7faa519149699e7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [1253a1a9442a15ebc27e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (1,2,0): 1253a1a9442a15ebc27e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (2) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (2): 1253a1a9442a15ebc27e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 118001100000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x10: {"_cls_":"setConcentrator","_id_":16,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Set concentrator type: {"_cls_":"setConcentrator","_id_":16,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> setSourceRouteDiscoveryMode: {"mode":1} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"setSourceRouteDiscoveryMode","_id_":90,"_isRequest_":true,"mode":1} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (2,2,0): 1200015a0001 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [225021a90e2a1494327e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [2350a1a90e2a3db2599476767e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (2,3,0): 2350a1a90e2a3db2599476767e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (3): 2350a1a90e2a3db2599476767e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 1280015a0028000000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x5a: {"_cls_":"setSourceRouteDiscoveryMode","_id_":90,"_isRequest_":false,"remainingTime":40} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-19 10:28:52] 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-19 10:28:52] 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-19 10:28:52] debug: zh:ezsp:uart: --> DATA (3,3,0): 1300010200010401efbe0007140000030006000a0019001a0000030000030004000500060008002000000300040204050406040005010b030b040b0207001001fc02fc [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [335121a9562a14b6587bf425ad4192499f4e21abe7ce7d5e8be7c6638afc7d5e3ca7efcddb6f89ffcfdbf5d2698f4627abe8733fa3ee758740934f2d17bae277380c0ffbb91990397e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [3451a1a9562a1582527e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (3,4,0): 3451a1a9562a1582527e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (4): 3451a1a9562a1582527e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 138001020000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x2: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-19 10:28:52] debug: zh:ezsp:driv: Ezsp adding endpoint: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> addEndpoint: {"endpoint":242,"profileId":41440,"deviceId":97,"appFlags":0,"inputClusterCount":0,"outputClusterCount":1,"inputClusterList":[],"outputClusterList":[33]} [2024-11-19 10:28:52] 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-19 10:28:52] debug: zh:ezsp:uart: --> DATA (4,4,0): 1400010200f2e0a161000000012100 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [445621a9562ae752f8f54a25aa54b349844e7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [4556a1a9562a15767d337e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (4,5,0): 4556a1a9562a1576137e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (5) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (5): 4556a1a9562a1576137e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 148001020000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x2: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-19 10:28:52] debug: zh:ezsp:driv: Ezsp adding endpoint: {"_cls_":"addEndpoint","_id_":2,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Get EzspValueId.VALUE_VERSION_INFO [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> getValue: {"valueId":17} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"getValue","_id_":170,"_isRequest_":true,"valueId":17} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (5,5,0): 150001aa0011 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [555721a9fe2a0430357e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [5657a1a9fe2a15b5e9944d26ab5538abde7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (5,6,0): 5657a1a9fe2a15b5e9944d26ab5538abde7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (6) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (6): 5657a1a9fe2a15b5e9944d26ab5538abde7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 158001aa000007b00007030100aa [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0xaa: {"_cls_":"getValue","_id_":170,"_isRequest_":false,"status":0,"value":{"type":"Buffer","data":[176,0,7,3,1,0,170]}} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Got EzspValueId.VALUE_VERSION_INFO = �� [2024-11-19 10:28:52] debug: zh:ezsp:driv: EmberZNet version: 7.3.1.0 build 176 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> networkInit: undefined [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"networkInit","_id_":23,"_isRequest_":true} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (6,6,0): 1600011700 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [665421a9432aff9d7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [6754a5a9432a15d2d37e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (6,7,0): 6754a5a9432a15d2d37e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (7) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (7): 6754a5a9432a15d2d37e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 168401170000 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x17: {"_cls_":"networkInit","_id_":23,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: Network init result: {"_cls_":"networkInit","_id_":23,"_isRequest_":false,"status":0} [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [7754b1a94d2a85f04c7e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (7,7,0): 7754b1a94d2a85f04c7e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (0) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8070787e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (7): 7754b1a94d2a85f04c7e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 169001190090 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x19: {"_cls_":"stackStatusHandler","_id_":25,"_isRequest_":false,"status":144} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> getNetworkParameters: undefined [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (7,0,0): 1700012800 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [705521a97c2ad7027e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (0) [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- [0055a1a97c2a15b333fb6a602409107d3a484822a0edce678bfd3e9c8e37627e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- DATA (0,0,0): 0055a1a97c2a15b333fb6a602409101a484822a0edce678bfd3e9c8e37627e [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> ACK (1) [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [8160597e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: <-- ACK (0): 0055a1a97c2a15b333fb6a602409101a484822a0edce678bfd3e9c8e37627e [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== Frame: 178001280000016a6f20458e5c8253d406050b0000000000f8ff07 [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[106,111,32,69,142,92,130,83],"panId":1748,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}} [2024-11-19 10:28:52] debug: zh:ezsp:uart: -+- waiting (0) success [2024-11-19 10:28:52] debug: zh:ezsp:driv: Current Node type: 1, Network parameters: EmberNetworkParameters: {"extendedPanId":[106,111,32,69,142,92,130,83],"panId":1748,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680} [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> networkState: undefined [2024-11-19 10:28:52] debug: zh:ezsp:ezsp: ==> {"_cls_":"networkState","_id_":24,"_isRequest_":true} [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> DATA (0,1,0): 1800011800 [2024-11-19 10:28:52] debug: zh:ezsp:uart: --> [015a21a94c2ab7527e] [2024-11-19 10:28:52] debug: zh:ezsp:uart: -?- waiting (1) [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [7d315aa1a94c2a17f2417e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (1,1,0): 115aa1a94c2a17f2417e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (2) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (1): 115aa1a94c2a17f2417e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 188001180002 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x18: {"_cls_":"networkState","_id_":24,"_isRequest_":false,"status":2} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (1) success [2024-11-19 10:28:53] debug: zh:ezsp:driv: Network state 2 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> getNetworkParameters: undefined [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":true} [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> DATA (1,2,0): 1900012800 [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [125b21a97c2accf27e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: -?- waiting (2) [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [225ba1a97c2a15b333fb6a602409107d3a484822a0edce678bfd3e9c8e782a7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (2,2,0): 225ba1a97c2a15b333fb6a602409101a484822a0edce678bfd3e9c8e782a7e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (3) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [83401b7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (2): 225ba1a97c2a15b333fb6a602409101a484822a0edce678bfd3e9c8e782a7e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 198001280000016a6f20458e5c8253d406050b0000000000f8ff07 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x28: {"_cls_":"getNetworkParameters","_id_":40,"_isRequest_":false,"status":0,"nodeType":1,"parameters":{"extendedPanId":[106,111,32,69,142,92,130,83],"panId":1748,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (2) success [2024-11-19 10:28:53] debug: zh:ezsp:driv: Node type: 1, Network parameters: EmberNetworkParameters: {"extendedPanId":[106,111,32,69,142,92,130,83],"panId":1748,"radioTxPower":5,"radioChannel":11,"joinMethod":0,"nwkManagerId":0,"nwkUpdateId":0,"channels":134215680} [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> getNodeId: undefined [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> {"_cls_":"getNodeId","_id_":39,"_isRequest_":true} [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> DATA (2,3,0): 1a00012700 [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [235821a9732a58327e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: -?- waiting (3) [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [3358a1a9732a15b2038c7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (3,3,0): 3358a1a9732a15b2038c7e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (4) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (3): 3358a1a9732a15b2038c7e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1a800127000000 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x27: {"_cls_":"getNodeId","_id_":39,"_isRequest_":false,"nodeId":0} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (3) success [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> getEui64: undefined [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> {"_cls_":"getEui64","_id_":38,"_isRequest_":true} [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> DATA (3,4,0): 1b00012600 [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [345921a9722a7d33977e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: -?- waiting (4) [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [4459a1a9722a6b30e56ab5fe1ed171177e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (4,4,0): 4459a1a9722a6b30e56ab5fe1ed171177e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (5) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [8520dd7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (4): 4459a1a9722a6b30e56ab5fe1ed171177e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1b800126007e82bcfeffdbb484 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x26: {"_cls_":"getEui64","_id_":38,"_isRequest_":false,"eui64":{"type":"Buffer","data":[132,180,219,255,254,188,130,126]}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (4) success [2024-11-19 10:28:53] debug: zh:ezsp:driv: Network ready [2024-11-19 10:28:53] debug: zh:ezsp: Device join request received: 0 84b4dbfffebc827e [2024-11-19 10:28:53] debug: zh:ezsp: Requesting 'Node Descriptor' for '0' [2024-11-19 10:28:53] debug: zh:ezsp:driv: ZDO Node_Desc_req params: {"dstaddr":0} [2024-11-19 10:28:53] debug: zh:ezsp:driv: EZSP nwk=0, IEEE=0x84b4dbfffebc827e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> getKey: {"keyType":1} [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> {"_cls_":"getKey","_id_":106,"_isRequest_":true,"keyType":1} [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> DATA (4,5,0): 1c00016a0001 [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [455e21a93e2a147b6a7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: -?- waiting (5) [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> sendUnicast: {"type":0,"indexOrDestination":0,"apsFrame":{"profileId":0,"sequence":2,"clusterId":2,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":256},"messageTag":3,"message":{"type":"Buffer","data":[2,0,0]}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [555ea1a93e2a15a859953497816c278973099d1b3f48c352b8586319fc7d5e3fa7ebcdde7d310d4339240e66ed86c57e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (5,5,0): 555ea1a93e2a15a859953497816c278973099d1b3f48c352b8586319fc7e3fa7ebcdde110d4339240e66ed86c57e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (6) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [8610be7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (5): 555ea1a93e2a15a859953497816c278973099d1b3f48c352b8586319fc7e3fa7ebcdde110d4339240e66ed86c57e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1c80016a00001a00017eb22b39b5c0ef47bab0d286a4d9459e0090000000000000007e82bcfeffdbb484 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x6a: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"key":{"contents":[126,178,43,57,181,192,239,71,186,176,210,134,164,217,69,158]},"outgoingFrameCounter":36864,"sequenceNumber":0,"bitmask":26,"type":1,"incomingFrameCounter":0,"partnerEUI64":{"type":"Buffer","data":[132,180,219,255,254,188,130,126]}}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (5) success [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> {"_cls_":"sendUnicast","_id_":52,"_isRequest_":true,"type":0,"indexOrDestination":0,"apsFrame":{"profileId":0,"sequence":2,"clusterId":2,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":256},"messageTag":3,"message":{"type":"Buffer","data":[2,0,0]}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> DATA (5,6,0): 1d0001340000000000000200000000010000020303020000 [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [565f21a9602a15b259944a27aa5592499d4e27a9eecd658bfd976f7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: -?- waiting (6) [2024-11-19 10:28:53] debug: zh:ezsp:driv: TRUST_CENTER_LINK_KEY: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"key":{"contents":[126,178,43,57,181,192,239,71,186,176,210,134,164,217,69,158]},"outgoingFrameCounter":36864,"sequenceNumber":0,"bitmask":26,"type":1,"incomingFrameCounter":0,"partnerEUI64":{"type":"Buffer","data":[132,180,219,255,254,188,130,126]}}} [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> getKey: {"keyType":3} [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [665fa1a9602a15f071ef7e765fb5a97d312a15b259964a25aa5593499c0cd8abedce9874fec463893e537e065fb5a96b2a15b259944a27aa5592499d4e27e9eece67e18b7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (6,6,0): 665fa1a9602a15f071ef7e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (7) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [87009f7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (6): 665fa1a9602a15f071ef7e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1d800134000042 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x34: {"_cls_":"sendUnicast","_id_":52,"_isRequest_":false,"status":0,"sequence":66} [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (7,6,0): 765fb5a9112a15b259964a25aa5593499c0cd8abedce9874fec463893e537e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (0) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [8070787e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (6): 765fb5a9112a15b259964a25aa5593499c0cd8abedce9874fec463893e537e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1d94014500000000020000000001000042ff000000ffff03020000 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x45: {"_cls_":"incomingMessageHandler","_id_":69,"_isRequest_":false,"type":0,"apsFrame":{"profileId":0,"sequence":66,"clusterId":2,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":256},"lastHopLqi":255,"lastHopRssi":0,"sender":0,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[2,0,0]}} [2024-11-19 10:28:53] debug: zh:ezsp: processMessage: {"messageType":0,"apsFrame":{"profileId":0,"sequence":66,"clusterId":2,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":256},"lqi":255,"rssi":0,"sender":0,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[2,0,0]}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (0,6,0): 065fb5a96b2a15b259944a27aa5592499d4e27e9eece67e18b7e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (1) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [8160597e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (6): 065fb5a96b2a15b259944a27aa5592499d4e27e9eece67e18b7e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1d94013f000000000000020000000001000042030000 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x3f: {"_cls_":"messageSentHandler","_id_":63,"_isRequest_":false,"type":0,"indexOrDestination":0,"apsFrame":{"profileId":0,"sequence":66,"clusterId":2,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":256},"messageTag":3,"status":0,"message":{"type":"Buffer","data":[]}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (6) success [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: ==> {"_cls_":"getKey","_id_":106,"_isRequest_":true,"keyType":3} [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> DATA (6,1,0): 1e00016a0003 [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [615c21a93e2a167f587e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: -?- waiting (7) [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [165fb1a97d312a15b25996ca25aa5593499c0dd8abedce9874ecc46389fc7d5e7f2826668cef8fbeeb5bd5d270177e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (1,6,0): 165fb1a9112a15b25996ca25aa5593499c0dd8abedce9874ecc46389fc7e7f2826668cef8fbeeb5bd5d270177e [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> ACK (2) GNU nano 7.2 zigbee2mqtt_log.txt [2024-11-19 11:24:38] ^[[34mdebug^[[39m: zh:ezsp:ezsp: <== 0x38: {"_cls_":"sendMulticast","_id_":56,"_isRequest_":false,"status":0,"sequence":21} [2024-11-19 11:24:38] ^[[34mdebug^[[39m: zh:ezsp:uart: -+- waiting (6) success [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:ezsp: ==> clearTransientLinkKeys: undefined [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:ezsp: ==> {"_cls_":"clearTransientLinkKeys","_id_":107,"_isRequest_":true} [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: --> DATA (6,3,0): 3600016b00 [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: --> [637421a93f2af91c7e] [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: -?- waiting (7) [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- [3774a1a93f2a527d317e] [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- DATA (3,7,0): 3774a1a93f2a52117e [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: --> ACK (4) [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: --> [8430fc7e] [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- ACK (7): 3774a1a93f2a52117e [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:ezsp: <== Frame: 3680016b00 [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:ezsp: <== 0x6b: {"_cls_":"clearTransientLinkKeys","_id_":107,"_isRequest_":false} [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:uart: -+- waiting (7) success [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:ezsp: ==> permitJoining: {"duration":0} [2024-11-19 11:24:39] ^[[34mdebug^[[39m: zh:ezsp:ezsp: ==> {"_cls_":"permitJoining","_id_":34,"_isRequest_":true,"duration":0} GNU nano 7.2 zigbee2mqtt_log.txt [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- DATA (7,4,0): 7401b1a9112a17b658924a24555593499ce3e767b6b29874f5c744cbfc7638a7ebbb8d7e [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: Unexpected DATA packet sequence 7 | 0: reject condition [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: --> [a0547d3a7e] [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- DATA (0,4,0): 0401b1a96b2a164fa6904b23aa546d499d4e270612ce678d707e [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: --> ACK (1) [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: --> [8160597e] [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- ACK (4): 0401b1a96b2a164fa6904b23aa546d499d4e270612ce678d707e [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:uart: Unexpected packet sequence 4 | 0 [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:ezsp: <== Frame: 4390013f0003fdff0401060001ff00010000adff0000 [2024-11-19 11:26:33] ^[[34mdebug^[[39m: zh:ezsp:ezsp: <== 0x1: {"_cls_":"getLibraryStatus","_id_":1,"_isRequest_":false,"status":63} [2024-11-19 11:26:34] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- [c1020b0a527e] [2024-11-19 11:26:34] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- RSTACK c1020b0a527e [2024-11-19 11:26:34] ^[[34mdebug^[[39m: zh:ezsp:uart: RSTACK Version: 2 Reason: RESET_SOFTWARE frame: c1020b0a527e [2024-11-19 11:26:34] ^[[34mdebug^[[39m: zh:ezsp:uart: -+- waiting reset success [2024-11-19 11:26:34] ^[[34mdebug^[[39m: zh:ezsp:uart: -+- waiting reset success [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:ezsp: ==> version: {"desiredProtocolVersion":4} [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:ezsp: ==> {"_cls_":"version","_id_":0,"_isRequest_":true,"desiredProtocolVersion":4} [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:uart: --> DATA (0,1,0): 00000004 [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:uart: --> [014221a850477d5d7e] [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:uart: -?- waiting (1) [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- [a0547d3a7e] [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:uart: <-- NAK (0): a0541a7e [2024-11-19 11:26:36] ^[[34mdebug^[[39m: zh:ezsp:uart: NAK Unexpected packet sequence 0 [2024-11-19 11:26:40] ^[[31merror^[[39m: zh:ezsp:uart: --> Error: Error: {"sequence":1} after 4000ms [2024-11-19 11:26:40] ^[[31merror^[[39m: zh:ezsp:uart: -!- break waiting (1) [2024-11-19 11:26:40] ^[[31merror^[[39m: zh:ezsp:uart: Can't send DATA frame (0,1,0): 00000004 [2024-11-19 11:26:41] ^[[34mdebug^[[39m: zh:ezsp:uart: ->> DATA (0,1,1): 00000004 [2024-11-19 11:26:41] ^[[34mdebug^[[39m: zh:ezsp:uart: --> [094221a85045507e] [2024-11-19 11:26:41] ^[[34mdebug^[[39m: zh:ezsp:uart: -?- rewaiting (1) [2024-11-19 11:26:45] ^[[31merror^[[39m: zh:ezsp:uart: --> Error: Error: {"sequence":1} after 4000ms [2024-11-19 11:26:45] ^[[31merror^[[39m: zh:ezsp:uart: -!- break rewaiting (1) [2024-11-19 11:26:45] ^[[31merror^[[39m: zh:ezsp:uart: Can't resend DATA frame (0,1,1): 00000004 [2024-11-19 11:26:45] ^[[34mdebug^[[39m: zh:ezsp:ezsp: onSerialReset() [2024-11-19 11:26:45] ^[[34mdebug^[[39m: zh:ezsp:driv: onEzspReset() [2024-11-19 11:26:45] ^[[34mdebug^[[39m: zh:ezsp:driv: Reset connection. [2024-11-19 11:26:45] ^[[34mdebug^[[39m: zh:ezsp:driv: Stopping driver [2024-11-19 11:26:45] ^[[34mdebug^[[39m: zh:ezsp:ezsp: Closing Ezsp [2024-11-19 11:26:45] ^[[34mdebug^[[39m: zh:ezsp:uart: Closing UART [2024-11-19 11:26:45] ^[[31merror^[[39m: z2m: Error while starting zigbee-herdsman [2024-11-19 11:26:45] ^[[31merror^[[39m: z2m: Failed to start zigbee [2024-11-19 11:26:45] ^[[31merror^[[39m: z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions [2024-11-19 11:26:45] ^[[31merror^[[39m: z2m: Exiting... [2024-11-19 11:26:45] ^[[31merror^[[39m: z2m: Error: Failure send version:{"type":"Buffer","data":[0,0,0,4]} at /opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/ezsp.ts:675:23 at Queue.execute (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/utils/queue.ts:36:20) at Ezsp.version (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/ezsp.ts:457:24) at Driver.startup (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/driver.ts:192:9) at Controller.start (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/controller/controller.ts:138:29) at Zigbee.start (/opt/zigbee2mqtt/lib/zigbee.ts:64:27) at Controller.start (/opt/zigbee2mqtt/lib/controller.ts:140:27) at start (/opt/zigbee2mqtt/index.js:154:5)