Zigbee2MQTT auf LNX mit zyklischen Warnings
-
für mich sieht es so aus, als wenn der Z2M Dienst alle ca 30 sek sich verabschiedet:
root@iobroker:~# sudo service zigbee2mqtt status * zigbee2mqtt.service - zigbee2mqtt Loaded: loaded (/etc/systemd/system/zigbee2mqtt.service; enabled; preset: enabled) Active: activating (auto-restart) (Result: timeout) since Mon 2024-11-18 19:08:43 CET; 1s ago Process: 5331 ExecStart=/usr/bin/node index.js (code=exited, status=1/FAILURE) Main PID: 5331 (code=exited, status=1/FAILURE) CPU: 2.278s root@iobroker:~# sudo service zigbee2mqtt status * zigbee2mqtt.service - zigbee2mqtt Loaded: loaded (/etc/systemd/system/zigbee2mqtt.service; enabled; preset: enabled) Active: activating (start) since Mon 2024-11-18 19:08:53 CET; 2s ago Main PID: 5367 (node) Tasks: 11 (limit: 18931) Memory: 98.0M CPU: 1.498s CGroup: /system.slice/zigbee2mqtt.service `-5367 /usr/bin/node index.js Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: --> ACK (5) Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: --> [8520dd7e] Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: <-- ACK (2): 4a0bb1a96b2a164fa674eb04aaa76d499db3d8fbe5ce67b2307e Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: Unexpected packet sequence 2 | 0 Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: Skipping the packet as repeated (5) Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: <-- Error c20251a8bd7e Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: Uart reseting Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: --> Write reset Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: --> [1ac038bc7e] Nov 18 19:08:54 iobroker node[5367]: [2024-11-18 19:08:54] debug: zh:ezsp:uart: -?- waiting reset root@iobroker:~# ^C root@iobroker:~# -
was ich komisch finde ist, dass es eine Pause in den Logs gibt: 22:37 - 07:20 Uhr:
-
kein plan was der da macht...
kannst du mal zigbee2mqtt manuell starten über konsole
zuerst service stoppen
in dem zigbee2mqtt Ordner wechseln und dannnpm startund dann gucken was da kommt..
-
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 -fkam 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 ??
-
-
@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ürist 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
