NEWS
Zigbee2MQTT auf LNX mit zyklischen Warnings
-
hier mal der anfang von der ausgabe:
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) [2024-11-19 10:28:53] debug: zh:ezsp:uart: --> [82503a7e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- ACK (6): 165fb1a9112a15b25996ca25aa5593499c0dd8abedce9874ecc46389fc7e7f2826668cef8fbeeb5bd5d270177e [2024-11-19 10:28:53] debug: zh:ezsp:uart: Unexpected packet sequence 6 | 7 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1d90014500000000028000000001000043ff000000ffff110200000000408fcdab528000412c800000 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x45: {"_cls_":"incomingMessageHandler","_id_":69,"_isRequest_":false,"type":0,"apsFrame":{"profileId":0,"sequence":67,"clusterId":32770,"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,0,0,64,143,205,171,82,128,0,65,44,128,0,0]}} [2024-11-19 10:28:53] debug: zh:ezsp:driv: Node_Desc_rsp frame: {"type":"Buffer","data":[2,0,0,0,0,64,143,205,171,82,128,0,65,44,128,0,0]} [2024-11-19 10:28:53] debug: zh:ezsp:driv: Node_Desc_rsp parsed: {"_cls_":"nodeDescRsp","_id_":32770,"transId":2,"status":0,"nwkaddr":0,"descriptor":{"byte1":0,"byte2":64,"mac_capability_flags":143,"manufacturer_code":43981,"maximum_buffer_size":82,"maximum_incoming_transfer_size":128,"server_mask":11329,"maximum_outgoing_transfer_size":128,"descriptor_capability_field":0}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- [275ca1a93e2a15b159978f4340c738bfebac33a1ff4ee34440546259277f3fa7ebcdde6f8fffc7dbd5d26929897e] [2024-11-19 10:28:53] debug: zh:ezsp:uart: <-- DATA (2,7,0): 275ca1a93e2a15b159978f4340c738bfebac33a1ff4ee34440546259277f3fa7ebcdde6f8fffc7dbd5d26929897e [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 (7): 275ca1a93e2a15b159978f4340c738bfebac33a1ff4ee34440546259277f3fa7ebcdde6f8fffc7dbd5d26929897e [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== Frame: 1e80016a0000030003c566ea92aaf677e2140a128084cfbd9201d0db0100000000000000000000000000 [2024-11-19 10:28:53] debug: zh:ezsp:ezsp: <== 0x6a: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"key":{"contents":[197,102,234,146,170,246,119,226,20,10,18,128,132,207,189,146]},"outgoingFrameCounter":31182849,"sequenceNumber":0,"bitmask":3,"type":3,"incomingFrameCounter":0,"partnerEUI64":{"type":"Buffer","data":[0,0,0,0,0,0,0,0]}}} [2024-11-19 10:28:53] debug: zh:ezsp:uart: -+- waiting (7) success [2024-11-19 10:28:53] debug: zh:ezsp:driv: CURRENT_NETWORK_KEY: {"_cls_":"getKey","_id_":106,"_isRequest_":false,"status":0,"keyStruct":{"key":{"contents":[197,102,234,146,170,246,119,226,20,10,18,128,132,207,189,146]},"outgoingFrameCounter":31182849,"sequenceNumber":0,"bitmask":3,"type":3,"incomingFrameCounter":0,"partnerEUI64":{"type":"Buffer","data":[0,0,0,0,0,0,0,0]}}} ^C[2024-11-19 10:28:53] debug: zh:controller:device: Request Queue (0x84b4dbfffebc827e): default expiration timeout set to 0 [2024-11-19 10:28:53] debug: zh:controller:device: Request Queue (0x282c02bfffeae693): default expiration timeout set to 0 [2024-11-19 10:28:53] debug: zh:controller:device: Request Queue (0x282c02bfffec950b): default expiration timeout set to 0 [2024-11-19 10:28:53] debug: zh:controller:device: Request Queue (0x003c84fffe18aed5): default expiration timeout set to 43200000 [2024-11-19 10:28:53] debug: z2m: Saving state to file /opt/zigbee2mqtt/data/state.json [2024-11-19 10:28:53] error: z2m: Not connected to MQTT server! [2024-11-19 10:28:53] error: z2m: Cannot send message: topic: 'zigbee2mqtt/bridge/state', payload: '{"state":"offline"} [2024-11-19 10:28:53] info: z2m: Disconnecting from MQTT server [2024-11-19 10:28:53] info: z2m: Stopping zigbee-herdsman... [2024-11-19 10:28:53] debug: zh:controller:database: Writing database to '/opt/zigbee2mqtt/data/database.db' [2024-11-19 10:28:53] info: z2m: Stopped zigbee-herdsman [2024-11-19 10:28:53] info: z2m: Stopped Zigbee2MQTT
-
hier mal die mqtt logs:
mqtt.02024-11-19 08:31:44.865 info Client [mqttjs_b6def4ee] subscribes on "zigbee2mqtt/#" with regex /^mqtt\.0\.zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:31:44.865 info Client [mqttjs_b6def4ee] subscribes on "zigbee2mqtt/#" with regex /^zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:31:44.835 info Client [mqttjs_b6def4ee] connected with secret 1732001504835_6979 mqtt.0 2024-11-19 08:31:26.479 info Client [mqttjs_297b7318] connection closed: disconnected mqtt.0 2024-11-19 08:30:03.883 info Client [mqttjs_297b7318] publishOnSubscribe send all known states mqtt.0 2024-11-19 08:30:03.684 info Client [mqttjs_297b7318] subscribes on "zigbee2mqtt/#" with regex /^mqtt\.0\.zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:30:03.684 info Client [mqttjs_297b7318] subscribes on "zigbee2mqtt/#" with regex /^zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:30:03.653 info Client [mqttjs_297b7318] connected with secret 1732001403653_5414 mqtt.0 2024-11-19 08:29:45.478 info Client [mqttjs_3e32c692] connection closed: disconnected mqtt.0 2024-11-19 08:28:22.870 info Client [mqttjs_3e32c692] publishOnSubscribe send all known states mqtt.0 2024-11-19 08:28:22.671 info Client [mqttjs_3e32c692] subscribes on "zigbee2mqtt/#" with regex /^mqtt\.0\.zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:28:22.671 info Client [mqttjs_3e32c692] subscribes on "zigbee2mqtt/#" with regex /^zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:28:22.640 info Client [mqttjs_3e32c692] connected with secret 1732001302640_1750 mqtt.0 2024-11-19 08:28:04.478 info Client [mqttjs_f14b52fd] connection closed: disconnected mqtt.0 2024-11-19 08:26:41.793 info Client [mqttjs_f14b52fd] publishOnSubscribe send all known states mqtt.0 2024-11-19 08:26:41.593 info Client [mqttjs_f14b52fd] subscribes on "zigbee2mqtt/#" with regex /^mqtt\.0\.zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:26:41.593 info Client [mqttjs_f14b52fd] subscribes on "zigbee2mqtt/#" with regex /^zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:26:41.564 info Client [mqttjs_f14b52fd] connected with secret 1732001201564_9659 mqtt.0 2024-11-19 08:26:23.478 info Client [mqttjs_9029bbbb] connection closed: disconnected mqtt.0 2024-11-19 08:25:01.034 info Client [mqttjs_9029bbbb] publishOnSubscribe send all known states mqtt.0 2024-11-19 08:25:00.834 info Client [mqttjs_9029bbbb] subscribes on "zigbee2mqtt/#" with regex /^mqtt\.0\.zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:25:00.834 info Client [mqttjs_9029bbbb] subscribes on "zigbee2mqtt/#" with regex /^zigbee2mqtt\..*/ mqtt.0 2024-11-19 08:25:00.805 info Client [mqttjs_9029bbbb] connected with secret 1732001100805_685 mqtt.0 2024-11-19 08:24:42.478 info Client [mqttjs_93e5d70e] connection closed: disconnected
-
du musst den schon länger laufen lassen bist der wieder ein
Start try again in 1 seconds...
anzeigt...
-
die ausgabe in eine datei ist nicht lange gelaufen. dann kam das:
root@iobroker:/opt/zigbee2mqtt# sudo service zigbee2mqtt stop root@iobroker:/opt/zigbee2mqtt# npm start >> zigbee2mqtt_log.txt /opt/zigbee2mqtt/node_modules/winston/node_modules/readable-stream/lib/_stream_writable.js:264 var er = new ERR_STREAM_WRITE_AFTER_END(); ^ Error: write after end at writeAfterEnd (/opt/zigbee2mqtt/node_modules/winston/node_modules/readable-stream/lib/_stream_writable.js:264:12) at DerivedLogger.Writable.write (/opt/zigbee2mqtt/node_modules/winston/node_modules/readable-stream/lib/_stream_writable.js:300:21) at DerivedLogger.log (/opt/zigbee2mqtt/node_modules/winston/lib/winston/logger.js:231:12) at Logger.log (/opt/zigbee2mqtt/lib/util/logger.ts:189:25) at Logger.debug (/opt/zigbee2mqtt/lib/util/logger.ts:210:14) at SerialDriver.onPortClose (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/uart.ts:380:16) at Object.onceWrapper (node:events:634:26) at Socket.emit (node:events:531:35) at TCP.<anonymous> (node:net:339:12) root@iobroker:/opt/zigbee2mqtt#
Hier die Datei:
zigbee2mqtt_log.txt -
hier noch die configuration.yaml:
homeassistant: false frontend: true mqtt: base_topic: zigbee2mqtt server: mqtt://192.168.0.22:1888 serial: port: tcp://192.168.0.33:6638 baudrate: 115200 adapter: ezsp advanced: network_key: - 197 - 102 - 234 - 146 - 170 - 246 - 119 - 226 - 20 - 10 - 18 - 128 - 132 - 207 - 189 - 146 pan_id: 1748 ext_pan_id: - 106 - 111 - 32 - 69 - 142 - 92 - 130 - 83 homeassistant_legacy_entity_attributes: false legacy_api: false legacy_availability_payload: false log_level: debug device_options: legacy: false devices: '0x282c02bfffeae693': friendly_name: wassersensor_1 '0x282c02bfffec950b': friendly_name: '0x282c02bfffec950b' '0x003c84fffe18aed5': friendly_name: '0x003c84fffe18aed5'
-
mit
sudo journalctl -u zigbee2mqtt.service -f
kam folgendes:
Nov 19 13:23:08 iobroker node[20281]: at SerialDriver.onPortClose (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/src/adapter/ezsp/driver/uart.ts:380:16) Nov 19 13:23:08 iobroker node[20281]: at Object.onceWrapper (node:events:634:26) Nov 19 13:23:08 iobroker node[20281]: at Socket.emit (node:events:531:35) Nov 19 13:23:08 iobroker node[20281]: at TCP.<anonymous> (node:net:339:12) Nov 19 13:23:08 iobroker systemd[1]: zigbee2mqtt.service: Main process exited, code=exited, status=1/FAILURE Nov 19 13:23:08 iobroker systemd[1]: zigbee2mqtt.service: Failed with result 'timeout'. Nov 19 13:23:08 iobroker systemd[1]: Failed to start zigbee2mqtt.service - zigbee2mqtt. Nov 19 13:23:08 iobroker systemd[1]: zigbee2mqtt.service: Consumed 2.326s CPU time. Nov 19 13:23:18 iobroker systemd[1]: zigbee2mqtt.service: Scheduled restart job, restart c
-
@tklein haswt du einen mosquitto installiert als mqtt server ??
-
brauche ich den, wenn der iob mqtt als server läuft?
-
@tklein sagte in Zigbee2MQTT auf LNX mit zyklischen Warnings:
brauche ich den, wenn der iob mqtt als server läuft?
da scheint irgendwas mit dem mqtt nicht zu stimmen...
stell mal den adapter so ein
und in der yaml von zigbee2mqtt mustt du es auch anpassten
server: mqtt://192.168.0.22:1884
-
@arteck jetzt sind die warnings weg thx dafür
ist das ein normales verhalten?
zigbee2mqtt.02024-11-20 09:12:59.275 info z2m: Zigbee2MQTT started! zigbee2mqtt.02024-11-20 09:12:59.275 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x282c02bfffec950b', payload '{"battery":99,"battery_low":false,"linkquality":196,"tamper":false,"update":{"installed_version":40,"latest_version":53,"state":"available"},"vibration":false,"voltage":3200,"x_axis":46,"y_axis":-46,"z_axis":1031}' zigbee2mqtt.0 2024-11-20 09:12:59.275 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/wassersensor_1', payload '{"battery":100,"battery_low":false,"linkquality":224,"tamper":false,"update":{"installed_version":58,"latest_version":66,"state":"available"},"voltage":3200,"water_leak":false}' zigbee2mqtt.0 2024-11-20 09:12:59.274 info z2m: Started frontend on port 8080 zigbee2mqtt.0 2024-11-20 09:11:18.123 info z2m: Zigbee2MQTT started! zigbee2mqtt.0 2024-11-20 09:11:18.123 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x282c02bfffec950b', payload '{"battery":99,"battery_low":false,"linkquality":196,"tamper":false,"update":{"installed_version":40,"latest_version":53,"state":"available"},"vibration":false,"voltage":3200,"x_axis":46,"y_axis":-46,"z_axis":1031}' zigbee2mqtt.0 2024-11-20 09:11:18.123 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/wassersensor_1', payload '{"battery":100,"battery_low":false,"linkquality":224,"tamper":false,"update":{"installed_version":58,"latest_version":66,"state":"available"},"voltage":3200,"water_leak":false}' zigbee2mqtt.0 2024-11-20 09:11:18.122 info z2m: Started frontend on port 8080 zigbee2mqtt.0 2024-11-20 09:09:36.983 info z2m: Zigbee2MQTT started! zigbee2mqtt.0 2024-11-20 09:09:36.983 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x282c02bfffec950b', payload '{"battery":99,"battery_low":false,"linkquality":196,"tamper":false,"update":{"installed_version":40,"latest_version":53,"state":"available"},"vibration":false,"voltage":3200,"x_axis":46,"y_axis":-46,"z_axis":1031}' zigbee2mqtt.0 2024-11-20 09:09:36.983 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/wassersensor_1', payload '{"battery":100,"battery_low":false,"linkquality":224,"tamper":false,"update":{"installed_version":58,"latest_version":66,"state":"available"},"voltage":3200,"water_leak":false}' zigbee2mqtt.0 2024-11-20 09:09:36.983 info z2m: Started frontend on port 8080 zigbee2mqtt.0 2024-11-20 09:07:55.987 info z2m: Zigbee2MQTT started! zigbee2mqtt.0 2024-11-20 09:07:55.987 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x282c02bfffec950b', payload '{"battery":99,"battery_low":false,"linkquality":196,"tamper":false,"update":{"installed_version":40,"latest_version":53,"state":"available"},"vibration":false,"voltage":3200,"x_axis":46,"y_axis":-46,"z_axis":1031}' zigbee2mqtt.0 2024-11-20 09:07:55.987 info z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/wassersensor_1', payload '{"battery":100,"battery_low":false,"linkquality":224,"tamper":false,"update":{"installed_version":58,"latest_version":66,"state":"available"},"voltage":3200,"water_leak":false}' zigbee2mqtt.0 2024-11-20 09:07:55.987 info z2m: Started frontend on port 8080 zigbee2mqtt.0 2024-11-20 09:06:15.147 info z2m: Zigbee2MQTT started!
hier noch die logs on der gui des coordinators:
[09:07:49] taskZB | New client: 192.168.0.22 id: 0 [09:09:19] taskZB | Client disconnected, id: 0 [09:09:30] taskZB | New client: 192.168.0.22 id: 0 [09:11:00] taskZB | Client disconnected, id: 0 [09:11:11] taskZB | New client: 192.168.0.22 id: 0 [09:12:41] taskZB | Client disconnected, id: 0 [09:12:52] taskZB | New client: 192.168.0.22 id: 0 [09:14:22] taskZB | Client disconnected, id: 0 [09:14:33] taskZB | New client: 192.168.0.22 id: 0 [09:16:03] taskZB | Client disconnected, id: 0 [09:16:14] taskZB | New client: 192.168.0.22 id: 0