-
Notifications
You must be signed in to change notification settings - Fork 3.2k
New issue
Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.
By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.
Already on GitHub? Sign in to your account
Add support for Develco (Frient) WISZB-131: Entry Sensor 2 Pro #8776
base: master
Are you sure you want to change the base?
Conversation
configure: async (device, coordinatorEndpoint, definition) => { | ||
/* | ||
* BUGFIX: When adding a new WISZB-131 contact sensor to z2m, if the magnet is never brought close to it, | ||
* genBinaryInput remains 'undiscovered'. This causes zstack to hang waiting for the sensor to discover |
There was a problem hiding this comment.
Choose a reason for hiding this comment
The reason will be displayed to describe this comment to others. Learn more.
Could you give some more context on the "waiting" part here? I don't understand why it would do that.
There was a problem hiding this comment.
Choose a reason for hiding this comment
The reason will be displayed to describe this comment to others. Learn more.
The "waiting" occurs because of the following scenario with the WISZB-131 sensor:
- We pair the new sensor with zigbee2mqtt successfully.
- After pairing, we never bring the magnet close to the sensor, so the magnetic switch is never activated.
- We then remove the batteries from the sensor.
- Upon restarting zigbee2mqtt, the system hangs (see attached log).
I acknowledge that there might be a more elegant solution to this issue, but it currently escapes my knowledge of the library internals. The simplest approach I have found is to force an injection of the genBinaryInput cluster with the default attribute “Magnet Open”. This work-around at least ensures that the endpoint gets registered, avoiding the hang on startup.
Log:
[2025-02-10 12:51:42] info: z2m: Logging to console (silenced), file (filename: log.log)
[2025-02-10 12:51:42] debug: z2m: Loaded state from file /app/data/state.json
[2025-02-10 12:51:42] info: z2m: Starting Zigbee2MQTT version 2.1.1 (commit #1accb8b66b95b99658c50e4c0209edcb946a5fe2)
[2025-02-10 12:51:42] debug: z2m: sd-notify loaded
[2025-02-10 12:51:42] info: z2m: Starting zigbee-herdsman (3.2.5)
[2025-02-10 12:51:42] debug: z2m: Using zigbee-herdsman with settings: '"{\"network\":{\"panID\":16455,\"extendedPanID\":[0,0,0,0,0,0,0,101],\"channelList\":[25],\"networkKey\":\"HIDDEN\"},\"databasePath\":\"/app/data/database.db\",\"databaseBackupPath\":\"/app/data/database.db.backup\",\"backupPath\":\"/app/data/coordinator_backup.json\",\"serialPort\":{\"path\":\"/dev/ttyUSB0\"},\"adapter\":{\"disableLED\":false,\"transmitPower\":20}}"'
[2025-02-10 12:51:42] debug: zh:adapter:discovery: Connected devices: [{"path":"/dev/ttyS0"},{"path":"/dev/ttyS1"},{"path":"/dev/ttyS2"},{"path":"/dev/ttyS3"},{"path":"/dev/ttyS4"},{"path":"/dev/ttyS5"},{"path":"/dev/ttyUSB0","manufacturer":"Silicon Labs","serialNumber":"b02487b8670cee1190482fd6f49e3369","pnpId":"usb-Silicon_Labs_CP2102N_USB_to_UART_Bridge_Controller_b02487b8670cee1190482fd6f49e3369-if00-port0","vendorId":"10c4","productId":"ea60"}]
[2025-02-10 12:51:42] info: zh:adapter:discovery: Matched adapter: {"path":"/dev/ttyUSB0","manufacturer":"Silicon Labs","serialNumber":"b02487b8670cee1190482fd6f49e3369","pnpId":"usb-Silicon_Labs_CP2102N_USB_to_UART_Bridge_Controller_b02487b8670cee1190482fd6f49e3369-if00-port0","vendorId":"10c4","productId":"ea60"} => zstack: path=/dev/ttyUSB0, score=2
[2025-02-10 12:51:42] debug: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":16455,"extendedPanID":[0,0,0,0,0,0,0,101],"channelList":[25]},"serialPort":{"path":"/dev/ttyUSB0","adapter":"zstack"},"adapter":{"disableLED":false,"transmitPower":20},"databasePath":"/app/data/database.db","databaseBackupPath":"/app/data/database.db.backup","backupPath":"/app/data/coordinator_backup.json"}'
[2025-02-10 12:51:42] info: zh:zstack:znp: Opening SerialPort with {"path":"/dev/ttyUSB0","baudRate":115200,"rtscts":false,"autoOpen":false}
[2025-02-10 12:51:43] info: zh:zstack:znp: Serialport opened
[2025-02-10 12:51:43] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:51:43] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:51:43] info: zh:zstack:znp: Writing CC2530/CC2531 skip bootloader payload
[2025-02-10 12:51:43] debug: zh:zstack:unpi:writer: --> buffer [239]
[2025-02-10 12:51:44] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:51:44] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:51:44] info: zh:zstack:znp: Skip bootloader for CC2652/CC1352
[2025-02-10 12:51:45] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:51:45] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:51:46] debug: zh:zstack:unpi:parser: <-- [254,6,65,128,0,2,1,2,7,1,192]
[2025-02-10 12:51:46] debug: zh:zstack:unpi:parser: --- parseNext [254,6,65,128,0,2,1,2,7,1,192]
[2025-02-10 12:51:46] debug: zh:zstack:unpi:parser: --> parsed 6 - 2 - 1 - 128 - [0,2,1,2,7,1] - 192
[2025-02-10 12:51:46] debug: zh:zstack:znp: <-- AREQ: SYS - resetInd - {"reason":0,"transportrev":2,"productid":1,"majorrel":2,"minorrel":7,"hwrev":1}
[2025-02-10 12:51:46] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,1,89,6,61]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,1,89,6,61]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 1 - [89,6] - 61
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - ping - {"capabilities":1625}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - version - {}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,0,33,2,35]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,10,97,2,2,1,2,7,1,70,217,52,1,0,196]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,10,97,2,2,1,2,7,1,70,217,52,1,0,196]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 10 - 3 - 1 - 2 - [2,1,2,7,1,70,217,52,1,0] - 196
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - version - {"transportrev":2,"product":1,"majorrel":2,"minorrel":7,"maintrel":1,"revision":20240710}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack: Adapter concurrent: 16
[2025-02-10 12:51:51] debug: zh:zstack: Detected znp version 'zStack3x0' ({"transportrev":2,"product":1,"majorrel":2,"minorrel":7,"maintrel":1,"revision":20240710})
[2025-02-10 12:51:51] debug: zh:adapter:zstack:manager: beginning znp startup
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - stackTune - {"operation":0,"value":20}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,15,0,20,56]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,1,97,15,0,111]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,1,97,15,0,111]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 1 - 15 - [0] - 111
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - stackTune - {"value":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":130}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,130,0,178]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,24,0,104]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,24,0,104]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [24,0] - 104
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":24}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":130,"offset":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,130,0,0,0,187]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,26,97,28,0,24,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0,105]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,26,97,28,0,24,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0,105]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 26 - 3 - 1 - 28 - [0,24,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0] - 105
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":24,"value":{"type":"Buffer","data":[0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0]}}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:adapter:zstack:manager: determining znp startup strategy
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":96}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,96,0,80]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,1,0,113]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,1,0,113]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [1,0] - 113
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":1}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":96,"offset":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,96,0,0,0,89]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,3,97,28,0,1,85,42]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,3,97,28,0,1,85,42]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 3 - 3 - 1 - 28 - [0,1,85] - 42
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":1,"value":{"type":"Buffer","data":[85]}}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":33}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,33,0,17]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,116,0,4]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,116,0,4]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [116,0] - 4
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":116}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":33,"offset":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,33,0,0,0,24]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,118,97,28,0,116,16,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,118,97,28,0,116,16,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,118,97,28,0,116,16,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101,0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 118 - 3 - 1 - 28 - [0,116,16,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101,0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0] - 82
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":116,"value":{"type":"Buffer","data":[16,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101,0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0]}}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":98}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,98,0,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,16,0,96]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,16,0,96]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [16,0] - 96
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":16}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":98,"offset":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,98,0,0,0,91]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,18,97,28,0,16,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,18,97,28,0,16,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 18 - 3 - 1 - 28 - [0,16,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146] - 82
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":16,"value":{"type":"Buffer","data":[180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146]}}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":58}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,58,0,10]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,17,0,97]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,17,0,97]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [17,0] - 97
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":17}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":58,"offset":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,58,0,0,0,3]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 19 - 3 - 1 - 28 - [0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146] - 82
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":17,"value":{"type":"Buffer","data":[0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146]}}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":59}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,59,0,11]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,17,0,97]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,17,0,97]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [17,0] - 97
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":17}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":59,"offset":0}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,59,0,0,0,2]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 19 - 3 - 1 - 28 - [0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146] - 82
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":17,"value":{"type":"Buffer","data":[0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146]}}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:adapter:zstack:manager: (stage-1) adapter is configured
[2025-02-10 12:51:51] debug: zh:adapter:zstack:manager: (stage-2) adapter state matches configuration
[2025-02-10 12:51:51] debug: zh:adapter:zstack:manager: determined startup strategy: startup
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: UTIL - getDeviceInfo - {}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,0,39,0,39]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: <-- [254,14,103,0,0,98,82,20,46,0,75,18,0,254,255,7,0,0,60]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext [254,14,103,0,0,98,82,20,46,0,75,18,0,254,255,7,0,0,60]
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --> parsed 14 - 3 - 7 - 0 - [0,98,82,20,46,0,75,18,0,254,255,7,0,0] - 60
[2025-02-10 12:51:51] debug: zh:zstack:znp: <-- SRSP: UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b002e145262","shortaddr":65534,"devicetype":7,"devicestate":0,"numassocdevices":0,"assocdeviceslist":[]}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:51] debug: zh:adapter:zstack:manager: starting adapter as coordinator
[2025-02-10 12:51:51] debug: zh:zstack:znp: --> SREQ: ZDO - startupFromApp - {"startdelay":100}
[2025-02-10 12:51:51] debug: zh:zstack:unpi:writer: --> frame [254,2,37,64,100,0,3]
[2025-02-10 12:51:53] debug: zh:controller:device: Request Queue (0x00124b002e145262): default expiration timeout set to 0
[2025-02-10 12:51:53] debug: zh:controller:device: Request Queue (0x0015bc001a02db57): default expiration timeout set to 3600000
[2025-02-10 12:51:53] debug: zh:controller:device: Request Queue (0x0015bc0044012556): default expiration timeout set to 3600000
[2025-02-10 12:51:53] debug: zh:controller:device: Request Queue (0x0015bc0044012584): default expiration timeout set to 3600000
[2025-02-10 12:51:53] debug: zh:controller:device: Request Queue (0x0015bc004401264c): default expiration timeout set to 3600000
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,101,64,0,36,254,1,69,192,9,141,254,3,79,128,13,0,4,197]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,101,64,0,36,254,1,69,192,9,141,254,3,79,128,13,0,4,197]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 5 - 64 - [0] - 36
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: ZDO - startupFromApp
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,69,192,9,141,254,3,79,128,13,0,4,197]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 2 - 5 - 192 - [9] - 141
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- AREQ: ZDO - stateChangeInd
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,3,79,128,13,0,4,197]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 15 - 128 - [13,0,4] - 197
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- AREQ: APP_CNF - bdbComissioningNotifcation - {"status":13}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: adapter successfully started in coordinator mode
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,4,37,5,0,0,0,0,36]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,101,5,0,97,254,6,69,133,0,0,0,0,0,0,198]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,101,5,0,97,254,6,69,133,0,0,0,0,0,0,198]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 5 - 5 - [0] - 97
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: ZDO - activeEpReq
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,6,69,133,0,0,0,0,0,0,198]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 6 - 2 - 5 - 133 - [0,0,0,0,0,0] - 198
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- AREQ: ZDO - activeEpRsp
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '1'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":1,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,1,4,1,5,0,0,0,0,0,44]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '2'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":2,"appprofid":257}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,2,1,1,5,0,0,0,0,0,42]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '3'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":3,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,3,4,1,5,0,0,0,0,0,46]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '4'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":4,"appprofid":263}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,4,7,1,5,0,0,0,0,0,42]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '5'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":5,"appprofid":264}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,5,8,1,5,0,0,0,0,0,36]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '6'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":6,"appprofid":265}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,6,9,1,5,0,0,0,0,0,38]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '8'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":8,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,8,4,1,5,0,0,0,0,0,37]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '10'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":10,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,10,4,1,5,0,0,0,0,0,39]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '11'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":1024,"appdevver":0,"appnuminclusters":2,"appinclusterlist":[1281,10],"appnumoutclusters":2,"appoutclusterlist":[1280,1282],"latencyreq":0,"endpoint":11,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,17,36,0,11,4,1,0,4,0,0,2,1,5,10,0,2,0,5,2,5,51]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '110'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":110,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,110,4,1,5,0,0,0,0,0,67]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '12'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":12,"appprofid":49246}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,12,94,192,5,0,0,0,0,0,186]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '13'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":1,"appinclusterlist":[25],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":13,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,11,36,0,13,4,1,5,0,0,0,1,25,0,0,58]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '47'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":47,"appprofid":260}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,47,4,1,5,0,0,0,0,0,2]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:adapter:zstack:manager: registering endpoint '242'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":242,"appprofid":41440}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,242,224,161,5,0,0,0,0,0,155]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: ZDO - extFindGroup - {"endpoint":242,"groupid":2948}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,3,37,74,242,132,11,17]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,19,101,74,0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,179]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,19,101,74,0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,179]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 19 - 3 - 5 - 74 - [0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0] - 179
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: ZDO - extFindGroup
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:controller: Started with result 'resumed'
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: ZDO - extNwkInfo - {}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,0,37,80,117]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,24,101,80,0,0,9,71,64,0,0,101,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,25,95]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,24,101,80,0,0,9,71,64,0,0,101,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,25,95]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 24 - 3 - 5 - 80 - [0,0,9,71,64,0,0,101,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,25] - 95
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: ZDO - extNwkInfo
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:controller: Injected database: true, adapter: true
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: UTIL - getDeviceInfo - {}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,0,39,0,39]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,20,103,0,0,98,82,20,46,0,75,18,0,0,0,7,9,3,127,107,1,59,94,162,255,254,3,79,128,8,2,0,198]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,20,103,0,0,98,82,20,46,0,75,18,0,0,0,7,9,3,127,107,1,59,94,162,255,254,3,79,128,8,2,0,198]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 20 - 3 - 7 - 0 - [0,98,82,20,46,0,75,18,0,0,0,7,9,3,127,107,1,59,94,162] - 255
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b002e145262","shortaddr":0,"devicetype":7,"devicestate":9,"numassocdevices":3,"assocdeviceslist":[27519,15105,41566]}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,3,79,128,8,2,0,198]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 15 - 128 - [8,2,0] - 198
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- AREQ: APP_CNF - bdbComissioningNotifcation - {"status":8}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:51:54] debug: zh:controller:endpoint: ZCL command 0x0015bc004401264c/35 genBinaryInput.read(["description"], {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":0,"reservedBits":0,"writeUndiv":false})
[2025-02-10 12:51:54] debug: zh:controller:endpoint: Request Queue (0x0015bc004401264c/35): send request
[2025-02-10 12:51:54] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,0,1)
[2025-02-10 12:51:54] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":1,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,1,0,30,5,16,2,0,28,0,239]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:51:54] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:51:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:01] debug: zh:zstack:unpi:parser: <-- [254,3,68,128,240,1,1,55]
[2025-02-10 12:52:01] debug: zh:zstack:unpi:parser: --- parseNext [254,3,68,128,240,1,1,55]
[2025-02-10 12:52:01] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 4 - 128 - [240,1,1] - 55
[2025-02-10 12:52:01] debug: zh:zstack:znp: <-- AREQ: AF - dataConfirm - {"status":240,"endpoint":1,"transid":1}
[2025-02-10 12:52:01] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:01] debug: zh:zstack: Data confirm error (0x0015bc004401264c:41566,240,0)
[2025-02-10 12:52:01] debug: zh:zstack: Wait 2000ms
[2025-02-10 12:52:03] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,1,1)
[2025-02-10 12:52:03] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":2,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:52:03] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,2,0,30,5,16,2,0,28,0,236]
[2025-02-10 12:52:03] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:52:03] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:52:03] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:52:03] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:52:03] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:12] info: z2m: Logging to console (silenced), file (filename: log.log)
[2025-02-10 12:52:12] debug: z2m: Loaded state from file /app/data/state.json
[2025-02-10 12:52:12] info: z2m: Starting Zigbee2MQTT version 2.1.1 (commit #1accb8b66b95b99658c50e4c0209edcb946a5fe2)
[2025-02-10 12:52:12] debug: z2m: sd-notify loaded
[2025-02-10 12:52:12] info: z2m: Starting zigbee-herdsman (3.2.5)
[2025-02-10 12:52:12] debug: z2m: Using zigbee-herdsman with settings: '"{\"network\":{\"panID\":16455,\"extendedPanID\":[0,0,0,0,0,0,0,101],\"channelList\":[25],\"networkKey\":\"HIDDEN\"},\"databasePath\":\"/app/data/database.db\",\"databaseBackupPath\":\"/app/data/database.db.backup\",\"backupPath\":\"/app/data/coordinator_backup.json\",\"serialPort\":{\"path\":\"/dev/ttyUSB0\"},\"adapter\":{\"disableLED\":false,\"transmitPower\":20}}"'
[2025-02-10 12:52:12] debug: zh:adapter:discovery: Connected devices: [{"path":"/dev/ttyS0"},{"path":"/dev/ttyS1"},{"path":"/dev/ttyS2"},{"path":"/dev/ttyS3"},{"path":"/dev/ttyS4"},{"path":"/dev/ttyS5"},{"path":"/dev/ttyUSB0","manufacturer":"Silicon Labs","serialNumber":"b02487b8670cee1190482fd6f49e3369","pnpId":"usb-Silicon_Labs_CP2102N_USB_to_UART_Bridge_Controller_b02487b8670cee1190482fd6f49e3369-if00-port0","vendorId":"10c4","productId":"ea60"}]
[2025-02-10 12:52:12] info: zh:adapter:discovery: Matched adapter: {"path":"/dev/ttyUSB0","manufacturer":"Silicon Labs","serialNumber":"b02487b8670cee1190482fd6f49e3369","pnpId":"usb-Silicon_Labs_CP2102N_USB_to_UART_Bridge_Controller_b02487b8670cee1190482fd6f49e3369-if00-port0","vendorId":"10c4","productId":"ea60"} => zstack: path=/dev/ttyUSB0, score=2
[2025-02-10 12:52:12] debug: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":16455,"extendedPanID":[0,0,0,0,0,0,0,101],"channelList":[25]},"serialPort":{"path":"/dev/ttyUSB0","adapter":"zstack"},"adapter":{"disableLED":false,"transmitPower":20},"databasePath":"/app/data/database.db","databaseBackupPath":"/app/data/database.db.backup","backupPath":"/app/data/coordinator_backup.json"}'
[2025-02-10 12:52:12] info: zh:zstack:znp: Opening SerialPort with {"path":"/dev/ttyUSB0","baudRate":115200,"rtscts":false,"autoOpen":false}
[2025-02-10 12:52:12] info: zh:zstack:znp: Serialport opened
[2025-02-10 12:52:12] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:52:12] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:52:13] info: zh:zstack:znp: Writing CC2530/CC2531 skip bootloader payload
[2025-02-10 12:52:13] debug: zh:zstack:unpi:writer: --> buffer [239]
[2025-02-10 12:52:14] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:52:14] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:52:14] info: zh:zstack:znp: Skip bootloader for CC2652/CC1352
[2025-02-10 12:52:14] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:52:14] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:52:17] debug: zh:zstack:unpi:parser: <-- [254,6,65,128,0,2,1,2,7,1,192]
[2025-02-10 12:52:17] debug: zh:zstack:unpi:parser: --- parseNext [254,6,65,128,0,2,1,2,7,1,192]
[2025-02-10 12:52:17] debug: zh:zstack:unpi:parser: --> parsed 6 - 2 - 1 - 128 - [0,2,1,2,7,1] - 192
[2025-02-10 12:52:17] debug: zh:zstack:znp: <-- AREQ: SYS - resetInd - {"reason":0,"transportrev":2,"productid":1,"majorrel":2,"minorrel":7,"hwrev":1}
[2025-02-10 12:52:17] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - ping - {"capabilities":1}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,0,33,1,32]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,2,97,1,89,6,61]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,1,89,6,61]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 1 - [89,6] - 61
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - ping - {"capabilities":1625}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - version - {}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,0,33,2,35]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,10,97,2,2,1,2,7,1,70,217,52,1,0,196]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,10,97,2,2,1,2,7,1,70,217,52,1,0,196]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 10 - 3 - 1 - 2 - [2,1,2,7,1,70,217,52,1,0] - 196
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - version - {"transportrev":2,"product":1,"majorrel":2,"minorrel":7,"maintrel":1,"revision":20240710}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack: Adapter concurrent: 16
[2025-02-10 12:52:20] debug: zh:zstack: Detected znp version 'zStack3x0' ({"transportrev":2,"product":1,"majorrel":2,"minorrel":7,"maintrel":1,"revision":20240710})
[2025-02-10 12:52:20] debug: zh:adapter:zstack:manager: beginning znp startup
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - stackTune - {"operation":0,"value":20}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,2,33,15,0,20,56]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,1,97,15,0,111]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,1,97,15,0,111]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 1 - 15 - [0] - 111
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - stackTune - {"value":0}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":130}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,130,0,178]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,24,0,104]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,24,0,104]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [24,0] - 104
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":24}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":130,"offset":0}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,130,0,0,0,187]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,26,97,28,0,24,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0,105]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,26,97,28,0,24,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0,105]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 26 - 3 - 1 - 28 - [0,24,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0] - 105
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":24,"value":{"type":"Buffer","data":[0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,1,0,32,172,182,0,0]}}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:adapter:zstack:manager: determining znp startup strategy
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":96}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,96,0,80]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,1,0,113]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,1,0,113]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [1,0] - 113
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":1}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":96,"offset":0}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,96,0,0,0,89]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,3,97,28,0,1,85,42]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,3,97,28,0,1,85,42]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 3 - 3 - 1 - 28 - [0,1,85] - 42
[2025-02-10 12:52:20] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":1,"value":{"type":"Buffer","data":[85]}}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:20] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":33}
[2025-02-10 12:52:20] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,33,0,17]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,116,0,4]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,116,0,4]
[2025-02-10 12:52:20] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [116,0] - 4
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":116}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":33,"offset":0}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,33,0,0,0,24]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,118,97,28,0,116,18,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,118,97,28,0,116,18,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0,80]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,118,97,28,0,116,18,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101,0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0,80]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 118 - 3 - 1 - 28 - [0,116,18,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101,0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0] - 80
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":116,"value":{"type":"Buffer","data":[18,5,2,51,15,51,0,30,0,0,0,1,5,1,143,0,8,0,2,13,2,0,0,0,25,0,0,0,0,0,0,0,0,0,0,0,71,64,8,0,0,0,0,2,15,15,4,0,1,0,0,0,1,0,0,0,0,101,0,0,0,0,0,0,0,1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,15,5,0,1,60,10,1,0,0,0,232,4,0,0]}}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":98}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,98,0,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,16,0,96]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,16,0,96]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [16,0] - 96
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":16}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":98,"offset":0}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,98,0,0,0,91]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,18,97,28,0,16,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,18,97,28,0,16,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 18 - 3 - 1 - 28 - [0,16,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146] - 82
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":16,"value":{"type":"Buffer","data":[180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146]}}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":58}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,58,0,10]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,17,0,97]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,17,0,97]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [17,0] - 97
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":17}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":58,"offset":0}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,58,0,0,0,3]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 19 - 3 - 1 - 28 - [0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146] - 82
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":17,"value":{"type":"Buffer","data":[0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146]}}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvLength - {"id":59}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,2,33,19,59,0,11]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,2,97,19,17,0,97]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,2,97,19,17,0,97]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 2 - 3 - 1 - 19 - [17,0] - 97
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvLength - {"length":17}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: SYS - osalNvReadExt - {"id":59,"offset":0}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,4,33,28,59,0,0,0,2]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,19,97,28,0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146,82]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 19 - 3 - 1 - 28 - [0,17,0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146] - 82
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: SYS - osalNvReadExt - {"status":0,"len":17,"value":{"type":"Buffer","data":[0,180,201,92,42,18,219,20,249,115,131,62,116,119,53,104,146]}}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:adapter:zstack:manager: (stage-1) adapter is configured
[2025-02-10 12:52:21] debug: zh:adapter:zstack:manager: (stage-2) adapter state matches configuration
[2025-02-10 12:52:21] debug: zh:adapter:zstack:manager: determined startup strategy: startup
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: UTIL - getDeviceInfo - {}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,0,39,0,39]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: <-- [254,14,103,0,0,98,82,20,46,0,75,18,0,254,255,7,0,0,60]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext [254,14,103,0,0,98,82,20,46,0,75,18,0,254,255,7,0,0,60]
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --> parsed 14 - 3 - 7 - 0 - [0,98,82,20,46,0,75,18,0,254,255,7,0,0] - 60
[2025-02-10 12:52:21] debug: zh:zstack:znp: <-- SRSP: UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b002e145262","shortaddr":65534,"devicetype":7,"devicestate":0,"numassocdevices":0,"assocdeviceslist":[]}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:21] debug: zh:adapter:zstack:manager: starting adapter as coordinator
[2025-02-10 12:52:21] debug: zh:zstack:znp: --> SREQ: ZDO - startupFromApp - {"startdelay":100}
[2025-02-10 12:52:21] debug: zh:zstack:unpi:writer: --> frame [254,2,37,64,100,0,3]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,101,64,0,36,254,1,69,192,9,141,254,3,79,128,13,0,4,197]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,101,64,0,36,254,1,69,192,9,141,254,3,79,128,13,0,4,197]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 5 - 64 - [0] - 36
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: ZDO - startupFromApp
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,69,192,9,141,254,3,79,128,13,0,4,197]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 2 - 5 - 192 - [9] - 141
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- AREQ: ZDO - stateChangeInd
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,3,79,128,13,0,4,197]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 15 - 128 - [13,0,4] - 197
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- AREQ: APP_CNF - bdbComissioningNotifcation - {"status":13}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: adapter successfully started in coordinator mode
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,4,37,5,0,0,0,0,36]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,101,5,0,97,254,6,69,133,0,0,0,0,0,0,198]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,101,5,0,97,254,6,69,133,0,0,0,0,0,0,198]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 5 - 5 - [0] - 97
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: ZDO - activeEpReq
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,6,69,133,0,0,0,0,0,0,198]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 6 - 2 - 5 - 133 - [0,0,0,0,0,0] - 198
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- AREQ: ZDO - activeEpRsp
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '1'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":1,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,1,4,1,5,0,0,0,0,0,44]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '2'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":2,"appprofid":257}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,2,1,1,5,0,0,0,0,0,42]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '3'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":3,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,3,4,1,5,0,0,0,0,0,46]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '4'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":4,"appprofid":263}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,4,7,1,5,0,0,0,0,0,42]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '5'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":5,"appprofid":264}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,5,8,1,5,0,0,0,0,0,36]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '6'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":6,"appprofid":265}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,6,9,1,5,0,0,0,0,0,38]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '8'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":8,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,8,4,1,5,0,0,0,0,0,37]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '10'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":10,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,10,4,1,5,0,0,0,0,0,39]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '11'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":1024,"appdevver":0,"appnuminclusters":2,"appinclusterlist":[1281,10],"appnumoutclusters":2,"appoutclusterlist":[1280,1282],"latencyreq":0,"endpoint":11,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,17,36,0,11,4,1,0,4,0,0,2,1,5,10,0,2,0,5,2,5,51]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '110'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":110,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,110,4,1,5,0,0,0,0,0,67]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '12'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":12,"appprofid":49246}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,12,94,192,5,0,0,0,0,0,186]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '13'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":1,"appinclusterlist":[25],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":13,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,11,36,0,13,4,1,5,0,0,0,1,25,0,0,58]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '47'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":47,"appprofid":260}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,47,4,1,5,0,0,0,0,0,2]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,3,79,128,8,2,0,198]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,3,79,128,8,2,0,198]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 15 - 128 - [8,2,0] - 198
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- AREQ: APP_CNF - bdbComissioningNotifcation - {"status":8}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:adapter:zstack:manager: registering endpoint '242'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - register - {"appdeviceid":5,"appdevver":0,"appnuminclusters":0,"appinclusterlist":[],"appnumoutclusters":0,"appoutclusterlist":[],"latencyreq":0,"endpoint":242,"appprofid":41440}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,9,36,0,242,224,161,5,0,0,0,0,0,155]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,0,0,101]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 0 - [0] - 101
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - register - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: ZDO - extFindGroup - {"endpoint":242,"groupid":2948}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,3,37,74,242,132,11,17]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,19,101,74,0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,179]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,19,101,74,0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,179]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 19 - 3 - 5 - 74 - [0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0] - 179
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: ZDO - extFindGroup
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:controller: Started with result 'resumed'
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: ZDO - extNwkInfo - {}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,0,37,80,117]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,24,101,80,0,0,9,71,64,0,0,101,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,25,95]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,24,101,80,0,0,9,71,64,0,0,101,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,25,95]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 24 - 3 - 5 - 80 - [0,0,9,71,64,0,0,101,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,25] - 95
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: ZDO - extNwkInfo
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:controller: Injected database: true, adapter: true
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: UTIL - getDeviceInfo - {}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,0,39,0,39]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,20,103,0,0,98,82,20,46,0,75,18,0,0,0,7,9,3,127,107,1,59,94,162,255]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,20,103,0,0,98,82,20,46,0,75,18,0,0,0,7,9,3,127,107,1,59,94,162,255]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 20 - 3 - 7 - 0 - [0,98,82,20,46,0,75,18,0,0,0,7,9,3,127,107,1,59,94,162] - 255
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b002e145262","shortaddr":0,"devicetype":7,"devicestate":9,"numassocdevices":3,"assocdeviceslist":[27519,15105,41566]}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:23] debug: zh:controller:device: Request Queue (0x00124b002e145262): default expiration timeout set to 0
[2025-02-10 12:52:23] debug: zh:controller:device: Request Queue (0x0015bc001a02db57): default expiration timeout set to 3600000
[2025-02-10 12:52:23] debug: zh:controller:device: Request Queue (0x0015bc0044012556): default expiration timeout set to 3600000
[2025-02-10 12:52:23] debug: zh:controller:device: Request Queue (0x0015bc0044012584): default expiration timeout set to 3600000
[2025-02-10 12:52:23] debug: zh:controller:device: Request Queue (0x0015bc004401264c): default expiration timeout set to 3600000
[2025-02-10 12:52:23] debug: zh:controller:endpoint: ZCL command 0x0015bc004401264c/35 genBinaryInput.read(["description"], {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":0,"reservedBits":0,"writeUndiv":false})
[2025-02-10 12:52:23] debug: zh:controller:endpoint: Request Queue (0x0015bc004401264c/35): send request
[2025-02-10 12:52:23] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,0,1)
[2025-02-10 12:52:23] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":1,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,1,0,30,5,16,2,0,28,0,239]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:52:23] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:52:23] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:30] debug: zh:zstack:unpi:parser: <-- [254,28,68,129,0,0,0,4,127,107,39,1,0,25,0,150,181,14,0,0,8,24,178,10,0,0,33,22,26,127,107,29,87]
[2025-02-10 12:52:30] debug: zh:zstack:unpi:parser: --- parseNext [254,28,68,129,0,0,0,4,127,107,39,1,0,25,0,150,181,14,0,0,8,24,178,10,0,0,33,22,26,127,107,29,87]
[2025-02-10 12:52:30] debug: zh:zstack:unpi:parser: --> parsed 28 - 2 - 4 - 129 - [0,0,0,4,127,107,39,1,0,25,0,150,181,14,0,0,8,24,178,10,0,0,33,22,26,127,107,29] - 87
[2025-02-10 12:52:30] debug: zh:zstack:znp: <-- AREQ: AF - incomingMsg - {"groupid":0,"clusterid":1024,"srcaddr":27519,"srcendpoint":39,"dstendpoint":1,"wasbroadcast":0,"linkquality":25,"securityuse":0,"timestamp":963990,"transseqnumber":0,"len":8,"data":{"type":"Buffer","data":[24,178,10,0,0,33,22,26]}}
[2025-02-10 12:52:30] debug: zh:controller: Received payload: clusterID=1024, address=27519, groupID=0, endpoint=39, destinationEndpoint=1, wasBroadcast=false, linkQuality=25, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":178,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":6678}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-02-10 12:52:30] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:31] debug: zh:zstack:unpi:parser: <-- [254,3,68,128,240,1,1,55]
[2025-02-10 12:52:31] debug: zh:zstack:unpi:parser: --- parseNext [254,3,68,128,240,1,1,55]
[2025-02-10 12:52:31] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 4 - 128 - [240,1,1] - 55
[2025-02-10 12:52:31] debug: zh:zstack:znp: <-- AREQ: AF - dataConfirm - {"status":240,"endpoint":1,"transid":1}
[2025-02-10 12:52:31] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:31] debug: zh:zstack: Data confirm error (0x0015bc004401264c:41566,240,0)
[2025-02-10 12:52:31] debug: zh:zstack: Wait 2000ms
[2025-02-10 12:52:33] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,1,1)
[2025-02-10 12:52:33] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":2,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:52:33] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,2,0,30,5,16,2,0,28,0,236]
[2025-02-10 12:52:33] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:52:33] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:52:33] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:52:33] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:52:33] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: <-- [254,3,68,128,240,1,2,52]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext [254,3,68,128,240,1,2,52]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 4 - 128 - [240,1,2] - 52
[2025-02-10 12:52:41] debug: zh:zstack:znp: <-- AREQ: AF - dataConfirm - {"status":240,"endpoint":1,"transid":2}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:41] debug: zh:zstack: Data confirm error (0x0015bc004401264c:41566,240,1)
[2025-02-10 12:52:41] debug: zh:zstack:znp: --> SREQ: UTIL - assocGetWithAddress - {"extaddr":"0x0015bc004401264c","nwkaddr":41566}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:writer: --> frame [254,10,39,74,76,38,1,68,0,188,21,0,94,162,29]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: <-- [254,36,103,74,94,162,4,0,1,8,0,0,6,1,170,0,0,0,0,0,0,0,255,128,7,0,0,111,7,0,0,0,255,0,0,0,0,0,0,0,186]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext [254,36,103,74,94,162,4,0,1,8,0,0,6,1,170,0,0,0,0,0,0,0,255,128,7,0,0,111,7,0,0,0,255,0,0,0,0,0,0,0,186]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --> parsed 36 - 3 - 7 - 74 - [94,162,4,0,1,8,0,0,6,1,170,0,0,0,0,0,0,0,255,128,7,0,0,111,7,0,0,0,255,0,0,0,0,0,0,0] - 186
[2025-02-10 12:52:41] debug: zh:zstack:znp: <-- SRSP: UTIL - assocGetWithAddress - {"nwkaddr":41566,"addridx":4,"noderelation":1}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:41] debug: zh:zstack: assocRemove(0x0015bc004401264c)
[2025-02-10 12:52:41] debug: zh:zstack:znp: --> SREQ: UTIL - assocRemove - {"ieeeadr":"0x0015bc004401264c"}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:writer: --> frame [254,8,39,99,76,38,1,68,0,188,21,0,202]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: <-- [254,1,103,99,0,5]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext [254,1,103,99,0,5]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 7 - 99 - [0] - 5
[2025-02-10 12:52:41] debug: zh:zstack:znp: <-- SRSP: UTIL - assocRemove - {"status":0}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:41] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,2,1)
[2025-02-10 12:52:41] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":3,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,3,0,30,5,16,2,0,28,0,237]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:52:41] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:52:41] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: <-- [254,28,68,129,0,0,0,4,127,107,39,1,0,25,0,191,41,26,0,0,8,24,179,10,0,0,33,11,26,127,107,29,234]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext [254,28,68,129,0,0,0,4,127,107,39,1,0,25,0,191,41,26,0,0,8,24,179,10,0,0,33,11,26,127,107,29,234]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --> parsed 28 - 2 - 4 - 129 - [0,0,0,4,127,107,39,1,0,25,0,191,41,26,0,0,8,24,179,10,0,0,33,11,26,127,107,29] - 234
[2025-02-10 12:52:42] debug: zh:zstack:znp: <-- AREQ: AF - incomingMsg - {"groupid":0,"clusterid":1024,"srcaddr":27519,"srcendpoint":39,"dstendpoint":1,"wasbroadcast":0,"linkquality":25,"securityuse":0,"timestamp":1714623,"transseqnumber":0,"len":8,"data":{"type":"Buffer","data":[24,179,10,0,0,33,11,26]}}
[2025-02-10 12:52:42] debug: zh:controller: Received payload: clusterID=1024, address=27519, groupID=0, endpoint=39, destinationEndpoint=1, wasBroadcast=false, linkQuality=25, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":179,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":6667}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: <-- [254,3,68,128,205,1,3,8]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext [254,3,68,128,205,1,3,8]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 4 - 128 - [205,1,3] - 8
[2025-02-10 12:52:42] debug: zh:zstack:znp: <-- AREQ: AF - dataConfirm - {"status":205,"endpoint":1,"transid":3}
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:42] debug: zh:zstack: Data confirm error (0x0015bc004401264c:41566,205,2)
[2025-02-10 12:52:42] debug: zh:zstack: assocAdd(0x0015bc004401264c)
[2025-02-10 12:52:42] debug: zh:zstack:znp: --> SREQ: UTIL - assocAdd - {"ieeeadr":"0x0015bc004401264c","nwkaddr":41566,"noderelation":1}
[2025-02-10 12:52:42] debug: zh:zstack:unpi:writer: --> frame [254,11,39,100,76,38,1,68,0,188,21,0,94,162,1,51]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: <-- [254,1,103,100,0,2]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext [254,1,103,100,0,2]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 7 - 100 - [0] - 2
[2025-02-10 12:52:42] debug: zh:zstack:znp: <-- SRSP: UTIL - assocAdd - {"status":0}
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:42] debug: zh:zstack: Discovering route to 41566
[2025-02-10 12:52:42] debug: zh:zstack:znp: --> SREQ: ZDO - extRouteDisc - {"dstAddr":41566,"options":0,"radius":30}
[2025-02-10 12:52:42] debug: zh:zstack:unpi:writer: --> frame [254,4,37,69,94,162,0,30,134]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: <-- [254,1,101,69,0,33]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext [254,1,101,69,0,33]
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 5 - 69 - [0] - 33
[2025-02-10 12:52:42] debug: zh:zstack:znp: <-- SRSP: ZDO - extRouteDisc
[2025-02-10 12:52:42] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:46] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,3,1)
[2025-02-10 12:52:46] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":4,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:52:46] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,4,0,30,5,16,2,0,28,0,234]
[2025-02-10 12:52:46] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:52:46] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:52:46] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:52:46] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:52:46] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: <-- [254,3,68,128,240,1,4,50]
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --- parseNext [254,3,68,128,240,1,4,50]
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 4 - 128 - [240,1,4] - 50
[2025-02-10 12:52:53] debug: zh:zstack:znp: <-- AREQ: AF - dataConfirm - {"status":240,"endpoint":1,"transid":4}
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:53] debug: zh:zstack: Data confirm error (0x0015bc004401264c:41566,240,3)
[2025-02-10 12:52:53] debug: zh:zstack: Request network address of '0x0015bc004401264c'
[2025-02-10 12:52:53] debug: zh:zstack:unpi:writer: --> frame [254,10,37,0,76,38,1,68,0,188,21,0,0,0,169]
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: <-- [254,1,101,0,0,100,254,21,69,255,0,0,0,0,128,0,0,0,0,0,76,38,1,68,0,188,21,0,94,162,0,85]
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --- parseNext [254,1,101,0,0,100,254,21,69,255,0,0,0,0,128,0,0,0,0,0,76,38,1,68,0,188,21,0,94,162,0,85]
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 5 - 0 - [0] - 100
[2025-02-10 12:52:53] debug: zh:zstack:znp: <-- SRSP: ZDO - nwkAddrReq
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --- parseNext [254,21,69,255,0,0,0,0,128,0,0,0,0,0,76,38,1,68,0,188,21,0,94,162,0,85]
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --> parsed 21 - 2 - 5 - 255 - [0,0,0,0,128,0,0,0,0,0,76,38,1,68,0,188,21,0,94,162,0] - 85
[2025-02-10 12:52:53] debug: zh:zstack:znp: <-- AREQ: ZDO - msgCbIncoming
[2025-02-10 12:52:53] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:52:54] debug: zh:zstack:unpi:parser: <-- [254,28,68,129,0,0,0,4,127,107,39,1,0,21,0,208,157,37,0,0,8,24,180,10,0,0,33,22,26,127,107,29,24]
[2025-02-10 12:52:54] debug: zh:zstack:unpi:parser: --- parseNext [254,28,68,129,0,0,0,4,127,107,39,1,0,21,0,208,157,37,0,0,8,24,180,10,0,0,33,22,26,127,107,29,24]
[2025-02-10 12:52:54] debug: zh:zstack:unpi:parser: --> parsed 28 - 2 - 4 - 129 - [0,0,0,4,127,107,39,1,0,21,0,208,157,37,0,0,8,24,180,10,0,0,33,22,26,127,107,29] - 24
[2025-02-10 12:52:54] debug: zh:zstack:znp: <-- AREQ: AF - incomingMsg - {"groupid":0,"clusterid":1024,"srcaddr":27519,"srcendpoint":39,"dstendpoint":1,"wasbroadcast":0,"linkquality":21,"securityuse":0,"timestamp":2465232,"transseqnumber":0,"len":8,"data":{"type":"Buffer","data":[24,180,10,0,0,33,22,26]}}
[2025-02-10 12:52:54] debug: zh:controller: Received payload: clusterID=1024, address=27519, groupID=0, endpoint=39, destinationEndpoint=1, wasBroadcast=false, linkQuality=21, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":180,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":6678}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-02-10 12:52:54] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:53:03] debug: zh:zstack: sendZclFrameToEndpointInternal 0x0015bc004401264c:41566/35 (0,4,1)
[2025-02-10 12:53:03] debug: zh:zstack:znp: --> SREQ: AF - dataRequest - {"dstaddr":41566,"destendpoint":35,"srcendpoint":1,"clusterid":15,"transid":5,"options":0,"radius":30,"len":5,"data":{"type":"Buffer","data":[16,2,0,28,0]}}
[2025-02-10 12:53:03] debug: zh:zstack:unpi:writer: --> frame [254,15,36,1,94,162,35,1,15,0,5,0,30,5,16,2,0,28,0,235]
[2025-02-10 12:53:03] debug: zh:zstack:unpi:parser: <-- [254,1,100,1,0,100]
[2025-02-10 12:53:03] debug: zh:zstack:unpi:parser: --- parseNext [254,1,100,1,0,100]
[2025-02-10 12:53:03] debug: zh:zstack:unpi:parser: --> parsed 1 - 3 - 4 - 1 - [0] - 100
[2025-02-10 12:53:03] debug: zh:zstack:znp: <-- SRSP: AF - dataRequest - {"status":0}
[2025-02-10 12:53:03] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:53:06] debug: zh:zstack:unpi:parser: <-- [254,28,68,129,0,0,0,4,127,107,39,1,0,25,0,135,16,49,0,0,8,24,181,10,0,0,33,17,26,127,107,29,220]
[2025-02-10 12:53:06] debug: zh:zstack:unpi:parser: --- parseNext [254,28,68,129,0,0,0,4,127,107,39,1,0,25,0,135,16,49,0,0,8,24,181,10,0,0,33,17,26,127,107,29,220]
[2025-02-10 12:53:06] debug: zh:zstack:unpi:parser: --> parsed 28 - 2 - 4 - 129 - [0,0,0,4,127,107,39,1,0,25,0,135,16,49,0,0,8,24,181,10,0,0,33,17,26,127,107,29] - 220
[2025-02-10 12:53:06] debug: zh:zstack:znp: <-- AREQ: AF - incomingMsg - {"groupid":0,"clusterid":1024,"srcaddr":27519,"srcendpoint":39,"dstendpoint":1,"wasbroadcast":0,"linkquality":25,"securityuse":0,"timestamp":3215495,"transseqnumber":0,"len":8,"data":{"type":"Buffer","data":[24,181,10,0,0,33,17,26]}}
[2025-02-10 12:53:06] debug: zh:controller: Received payload: clusterID=1024, address=27519, groupID=0, endpoint=39, destinationEndpoint=1, wasBroadcast=false, linkQuality=25, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":181,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":6673}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-02-10 12:53:06] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:53:11] debug: zh:zstack:unpi:parser: <-- [254,3,68,128,240,1,5,51]
[2025-02-10 12:53:11] debug: zh:zstack:unpi:parser: --- parseNext [254,3,68,128,240,1,5,51]
[2025-02-10 12:53:11] debug: zh:zstack:unpi:parser: --> parsed 3 - 2 - 4 - 128 - [240,1,5] - 51
[2025-02-10 12:53:11] debug: zh:zstack:znp: <-- AREQ: AF - dataConfirm - {"status":240,"endpoint":1,"transid":5}
[2025-02-10 12:53:11] debug: zh:zstack:unpi:parser: --- parseNext []
[2025-02-10 12:53:11] debug: zh:zstack: Data confirm error (0x0015bc004401264c:41566,240,4)
[2025-02-10 12:53:11] debug: zh:controller:endpoint: Request Queue (0x0015bc004401264c/35): queue request (transaction failed) (Error: Data request failed with error: 'MAC transaction expired' (240))
[2025-02-10 12:53:11] debug: zh:controller:requestqueue: Request Queue (0x0015bc004401264c/35): Sending when active. Expires: 1739191943712
[2025-02-10 12:53:19] debug: zh:zstack:unpi:parser: <-- [254,29,68,129,0,0,0,5,127,107,35,11,0,36,0,231,126,61,0,0,9,25,182,0,48,0,0,23,255,255,127,107,29,233]
[2025-02-10 12:53:19] debug: zh:zstack:unpi:parser: --- parseNext [254,29,68,129,0,0,0,5,127,107,35,11,0,36,0,231,126,61,0,0,9,25,182,0,48,0,0,23,255,255,127,107,29,233]
[2025-02-10 12:53:19] debug: zh:zstack:unpi:parser: --> parsed 29 - 2 - 4 - 129 - [0,0,0,5,127,107,35,11,0,36,0,231,126,61,0,0,9,25,182,0,48,0,0,23,255,255,127,107,29] - 233
[2025-02-10 12:53:19] debug: zh:zstack:znp: <-- AREQ: AF - incomingMsg - {"groupid":0,"clusterid":1280,"srcaddr":27519,"srcendpoint":35,"dstendpoint":11,"wasbroadcast":0,"linkquality":36,"securityuse":0,"timestamp":4030183,"transseqnumber":0,"len":9,"data":{"type":"Buffer","data":[25,182,0,48,0,0,23,255,255]}}
[2025-02-10 12:53:19] debug: zh:controller: Received payload: clusterID=1280, address=27519, groupID=0, endpoint=35, destinationEndpoint=11, wasBroadcast=false, linkQuality=36, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":182,"commandIdentifier":0},"payload":{"zonestatus":48,"extendedstatus":0},"command":{"ID":0,"parameters":[{"name":"zonestatus","type":33},{"name":"extendedstatus","type":32}],"name":"statusChangeNotification"}}
[2025-02-10 12:53:19] debug: zh:zstack:unpi:parser: --- parseNext []
There was a problem hiding this comment.
Choose a reason for hiding this comment
The reason will be displayed to describe this comment to others. Learn more.
It puzzles me why this attribute is read in the first place, does this even happen without a configure:()
here?
There was a problem hiding this comment.
Choose a reason for hiding this comment
The reason will be displayed to describe this comment to others. Learn more.
Yes, indeed, the very first version in which I tested the devices did not include the configure block, and that’s where the issues arose.
Without the configure block, in database.db, the devices (WISZB-131) that at some point activated the magnet display the genBinaryInput attribute.
The others do not. According to what I saw in the logs, it gets stuck on those that don’t have this parameter.
Here is the database.db that I'm using for testing:
{"id":1,"type":"Coordinator","ieeeAddr":"0x00124b002e145262","nwkAddr":0,"manufId":7,"epList":[1,2,3,4,5,6,8,10,11,12,13,47,110,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"2":{"profId":257,"epId":2,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"3":{"profId":260,"epId":3,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"4":{"profId":263,"epId":4,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"5":{"profId":264,"epId":5,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"6":{"profId":265,"epId":6,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"8":{"profId":260,"epId":8,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"10":{"profId":260,"epId":10,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"11":{"profId":260,"epId":11,"devId":1024,"inClusterList":[1281,10],"outClusterList":[1280,1282],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"12":{"profId":49246,"epId":12,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"13":{"profId":260,"epId":13,"devId":5,"inClusterList":[25],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"47":{"profId":260,"epId":47,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"110":{"profId":260,"epId":110,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"242":{"profId":41440,"epId":242,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}}},"interviewCompleted":true,"meta":{}}
{"id":2,"type":"EndDevice","ieeeAddr":"0x0015bc001a02db57","nwkAddr":27519,"manufId":4117,"manufName":"frient A/S","powerSource":"Battery","modelId":"MOSZB-140","epList":[1,34,35,38,39,40,41],"endpoints":{"1":{"profId":49353,"epId":1,"devId":1,"inClusterList":[3,5,6],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"34":{"profId":260,"epId":34,"devId":263,"inClusterList":[0,3,1030],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"develcoPrimarySwVersion":{"type":"Buffer","data":[4,0,6]},"develcoPrimaryHwVersion":{"type":"Buffer","data":[2,2,0]}}}},"binds":[],"configuredReportings":[],"meta":{}},"35":{"profId":260,"epId":35,"devId":1026,"inClusterList":[0,1,3,15,32,1280],"outClusterList":[3,10,25],"clusters":{"genBasic":{"attributes":{"modelId":"MOSZB-140","manufacturerName":"frient A/S","powerSource":3,"zclVersion":1,"dateCode":"20240703 13:42","develcoPrimarySwVersion":{"type":"Buffer","data":[4,0,6]},"develcoPrimaryHwVersion":{"type":"Buffer","data":[2,2,0]},"develcoLedControl":255}},"ssIasZone":{"attributes":{"iasCieAddr":"0x00124b002e145262","zoneState":1,"develcoAlarmOffDelay":20}},"genPollCtrl":{"attributes":{"checkinInterval":14400}},"genPowerCfg":{"attributes":{"batteryVoltage":31,"batteryPercentageRemaining":200,"batteryAlarmState":0}}},"binds":[{"cluster":32,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1},{"cluster":1,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1,"attrId":32,"minRepIntval":3600,"maxRepIntval":65000,"repChange":10}],"meta":{}},"38":{"profId":260,"epId":38,"devId":770,"inClusterList":[0,3,1026],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}},"msTemperatureMeasurement":{"attributes":{"measuredValue":1725}}},"binds":[{"cluster":1026,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1026,"attrId":0,"minRepIntval":10,"maxRepIntval":3600,"repChange":100}],"meta":{}},"39":{"profId":260,"epId":39,"devId":262,"inClusterList":[0,3,1024],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}},"msIlluminanceMeasurement":{"attributes":{"measuredValue":6673}}},"binds":[{"cluster":1024,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1024,"attrId":0,"minRepIntval":10,"maxRepIntval":3600,"repChange":5}],"meta":{}},"40":{"profId":260,"epId":40,"devId":263,"inClusterList":[0,3,1030],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}}},"binds":[],"configuredReportings":[],"meta":{}},"41":{"profId":260,"epId":41,"devId":263,"inClusterList":[0,3,1030],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}}},"binds":[],"configuredReportings":[],"meta":{}}},"hwVersion":"2.2.0","dateCode":"20240703 13:42","swBuildId":"4.0.6","zclVersion":1,"interviewCompleted":true,"meta":{"configured":332242049},"lastSeen":1739187711745,"checkinInterval":3600}
{"id":3,"type":"EndDevice","ieeeAddr":"0x0015bc0044012556","nwkAddr":15105,"manufId":4117,"manufName":"frient A/S","powerSource":"Battery","modelId":"WISZB-131","epList":[1,35,38],"endpoints":{"1":{"profId":49353,"epId":1,"devId":1,"inClusterList":[5,6],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"35":{"profId":260,"epId":35,"devId":1026,"inClusterList":[0,1,3,32,15,1280,2821],"outClusterList":[10,25],"clusters":{"genBasic":{"attributes":{"modelId":"WISZB-131","manufacturerName":"frient A/S","powerSource":3,"zclVersion":7,"dateCode":"2024-10-10 13:49","develcoPrimarySwVersion":{"type":"Buffer","data":[2,0,6]},"develcoPrimaryHwVersion":{"type":"Buffer","data":[4,0,0]}}},"ssIasZone":{"attributes":{"iasCieAddr":"0x00124b002e145262","zoneState":1}},"genPollCtrl":{"attributes":{"checkinInterval":14400}},"genPowerCfg":{"attributes":{"batteryVoltage":31,"batteryAlarmState":0,"batteryPercentageRemaining":200}},"genBinaryInput":{"attributes":{"description":"Magnet Open"}}},"binds":[{"cluster":32,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1},{"cluster":1,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1,"attrId":32,"minRepIntval":3600,"maxRepIntval":65000,"repChange":10}],"meta":{}},"38":{"profId":260,"epId":38,"devId":770,"inClusterList":[0,3,1026],"outClusterList":[3],"clusters":{"genBasic":{"attributes":{}},"msTemperatureMeasurement":{"attributes":{"measuredValue":1718}}},"binds":[{"cluster":1026,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1026,"attrId":0,"minRepIntval":10,"maxRepIntval":3600,"repChange":100}],"meta":{}}},"hwVersion":"4.0.0","dateCode":"2024-10-10 13:49","swBuildId":"2.0.6","zclVersion":7,"interviewCompleted":true,"meta":{"configured":332242049},"lastSeen":1739187643049,"checkinInterval":3600}
{"id":4,"type":"EndDevice","ieeeAddr":"0x0015bc004401264c","nwkAddr":41566,"manufId":4117,"manufName":"frient A/S","powerSource":"Battery","modelId":"WISZB-131","epList":[1,35,38],"endpoints":{"1":{"profId":49353,"epId":1,"devId":1,"inClusterList":[5,6],"outClusterList":[],"clusters":{},"binds":[],"configuredReportings":[],"meta":{}},"35":{"profId":260,"epId":35,"devId":1026,"inClusterList":[0,1,3,32,15,1280,2821],"outClusterList":[10,25],"clusters":{"genBasic":{"attributes":{"modelId":"WISZB-131","manufacturerName":"frient A/S","powerSource":3,"zclVersion":7,"dateCode":"2024-10-10 19:57","develcoPrimarySwVersion":{"type":"Buffer","data":[2,0,6]},"develcoPrimaryHwVersion":{"type":"Buffer","data":[4,0,0]}}},"ssIasZone":{"attributes":{"iasCieAddr":"0x00124b002e145262","zoneState":1}},"genPollCtrl":{"attributes":{"checkinInterval":14400}},"genPowerCfg":{"attributes":{"batteryVoltage":31,"batteryAlarmState":0,"batteryPercentageRemaining":200}}},"binds":[{"cluster":32,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1},{"cluster":1,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1,"attrId":32,"minRepIntval":3600,"maxRepIntval":65000,"repChange":10}],"meta":{}},"38":{"profId":260,"epId":38,"devId":770,"inClusterList":[0,3,1026],"outClusterList":[3],"clusters":{"genBasic":{"attributes":{}},"msTemperatureMeasurement":{"attributes":{"measuredValue":2231}}},"binds":[{"cluster":1026,"type":"endpoint","deviceIeeeAddress":"0x00124b002e145262","endpointID":1}],"configuredReportings":[{"cluster":1026,"attrId":0,"minRepIntval":10,"maxRepIntval":3600,"repChange":100}],"meta":{}}},"hwVersion":"4.0.0","dateCode":"2024-10-10 19:57","swBuildId":"2.0.6","zclVersion":7,"interviewCompleted":true,"meta":{"configured":332242049},"lastSeen":1739188181580,"checkinInterval":3600}
Devices were paired without the configure block. The device with id 3 has had the magnet sensor activated; the 4th doesn't.
There was a problem hiding this comment.
Choose a reason for hiding this comment
The reason will be displayed to describe this comment to others. Learn more.
So if I understand correctly, even without the configure
block it hangs? If yes, could you provide the debug log of this?
Added compatibility for the WISZB-131 contact sensor.
If the magnet is never activated during commissioning, the internal genBinaryInput endpoint remains undiscovered, which may cause zstack to hang on hub restart when the battery is removed:
This PR forces the injection of the missing genBinaryInput cluster (with the attribute "Magnet Open") to prevent that issue.