2022-05-23 18:37:40.348 - info: host.raspberrypi2 stopInstance system.adapter.zigbee.1 (force=false, process=true) 2022-05-23 18:37:40.438 - info: host.raspberrypi2 stopInstance system.adapter.zigbee.1 send kill signal 2022-05-23 18:37:40.438 - silly: zigbee.1 (4776) States system redis pmessage system.adapter.zigbee.1.sigKill/system.adapter.zigbee.1.sigKill:{"val":-1,"ack":false,"ts":1653323860351,"q":0,"from":"system.host.raspberrypi2","lc":1653323860351} 2022-05-23 18:37:40.439 - info: zigbee.1 (4776) Got terminate signal TERMINATE_YOURSELF 2022-05-23 18:37:40.441 - info: zigbee.1 (4776) cleaned everything up... 2022-05-23 18:37:40.443 - info: zigbee.1 (4776) Zigbee: disabling joining new devices. 2022-05-23 18:37:40.927 - info: zigbee.1 (4776) terminating 2022-05-23 18:37:40.929 - debug: zigbee.1 (4776) Plugin sentry destroyed 2022-05-23 18:37:40.930 - info: zigbee.1 (4776) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason 2022-05-23 18:37:41.438 - info: host.raspberrypi2 stopInstance system.adapter.zigbee.1 killing pid 4776 2022-05-23 18:37:41.700 - info: host.raspberrypi2 instance system.adapter.zigbee.1 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION) 2022-05-23 18:37:43.756 - info: host.raspberrypi2 instance system.adapter.zigbee.1 started with pid 4854 2022-05-23 18:37:47.118 - debug: zigbee.1 (4854) Redis Objects: Use Redis connection: 127.0.0.1:9001 2022-05-23 18:37:47.200 - debug: zigbee.1 (4854) Objects client ready ... initialize now 2022-05-23 18:37:47.205 - debug: zigbee.1 (4854) Objects create System PubSub Client 2022-05-23 18:37:47.207 - debug: zigbee.1 (4854) Objects create User PubSub Client 2022-05-23 18:37:47.375 - debug: zigbee.1 (4854) Objects client initialize lua scripts 2022-05-23 18:37:47.393 - debug: zigbee.1 (4854) Objects connected to redis: 127.0.0.1:9001 2022-05-23 18:37:47.398 - silly: zigbee.1 (4854) redis psubscribe cfg.o.system.user.* 2022-05-23 18:37:47.463 - silly: zigbee.1 (4854) redis psubscribe cfg.o.enum.* 2022-05-23 18:37:47.469 - silly: zigbee.1 (4854) objectDB connected 2022-05-23 18:37:47.472 - debug: zigbee.1 (4854) Redis States: Use Redis connection: 127.0.0.1:9000 2022-05-23 18:37:47.505 - debug: zigbee.1 (4854) States create System PubSub Client 2022-05-23 18:37:47.506 - debug: zigbee.1 (4854) States create User PubSub Client 2022-05-23 18:37:47.563 - debug: zigbee.1 (4854) States connected to redis: 127.0.0.1:9000 2022-05-23 18:37:47.564 - silly: zigbee.1 (4854) statesDB connected 2022-05-23 18:37:47.829 - debug: zigbee.1 (4854) Plugin sentry Initialize Plugin (enabled=true) 2022-05-23 18:37:47.837 - info: zigbee.1 (4854) Plugin sentry Sentry Plugin disabled for this process because sending of statistic data is disabled for the system 2022-05-23 18:37:50.711 - info: zigbee.1 (4854) starting. Version 1.6.18 in /opt/iobroker/node_modules/iobroker.zigbee, node: v12.22.12, js-controller: 4.0.23 2022-05-23 18:37:50.762 - debug: zigbee.1 (4854) Using zigbee-herdsman with settings: {"network":{"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11],"networkKey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]},"databasePath":"/opt/iobroker/iobroker-data/zigbee_1/shepherd.db","backupPath":"/opt/iobroker/iobroker-data/zigbee_1/nvbackup.json","serialPort":{"baudRate":115200,"rtscts":false,"path":"/dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0014D902D9-if00","adapter":"zstack"},"adapter":{"forceStartWithInconsistentAdapterConfiguration":true}} 2022-05-23 18:37:50.857 - info: zigbee.1 (4854) Starting Zigbee npm ... 2022-05-23 18:37:50.859 - debug: zigbee.1 (4854) Starting zigbee-herdsman... 2022-05-23 18:37:51.545 - debug: zigbee.1 (4854) zigbee-herdsman:adapter Path '/dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0014D902D9-if00' is valid for 'ZStackAdapter' 2022-05-23 18:37:51.550 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13],"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"baudRate":115200,"rtscts":false,"path":"/dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0014D902D9-if00","adapter":"zstack"},"databasePath":"/opt/iobroker/iobroker-data/zigbee_1/shepherd.db","databaseBackupPath":null,"backupPath":"/opt/iobroker/iobroker-data/zigbee_1/nvbackup.json","adapter":{"disableLED":false,"forceStartWithInconsistentAdapterConfiguration":true},"acceptJoiningDeviceHandler":null}' 2022-05-23 18:37:51.552 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:log Opening SerialPort with /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0014D902D9-if00 and {"baudRate":115200,"rtscts":false,"autoOpen":false} 2022-05-23 18:37:51.571 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:log Serialport opened 2022-05-23 18:37:51.576 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1} 2022-05-23 18:37:51.580 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32] 2022-05-23 18:37:51.606 - debug: zigbee.1 (4854) Backup /opt/iobroker/iobroker-data/zigbee_1/backup_2022_05_23-18_37_50.tar.gz success 2022-05-23 18:37:51.608 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,1,121,1,26] 2022-05-23 18:37:51.610 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,1,121,1,26] 2022-05-23 18:37:51.612 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 1 - [121,1] - 26 2022-05-23 18:37:51.615 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - ping - {"capabilities":377} 2022-05-23 18:37:51.618 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.619 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1} 2022-05-23 18:37:51.621 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32] 2022-05-23 18:37:51.635 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,1,121,1,26] 2022-05-23 18:37:51.637 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,1,121,1,26] 2022-05-23 18:37:51.638 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 1 - [121,1] - 26 2022-05-23 18:37:51.639 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - ping - {"capabilities":377} 2022-05-23 18:37:51.640 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.642 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - version - {} 2022-05-23 18:37:51.643 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,2,35] 2022-05-23 18:37:51.650 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,97,2,2,0,2,6,3,171,101,52,1,2,0,0,0,0,145] 2022-05-23 18:37:51.651 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,97,2,2,0,2,6,3,171,101,52,1,2,0,0,0,0,145] 2022-05-23 18:37:51.652 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 3 - 1 - 2 - [2,0,2,6,3,171,101,52,1,2,0,0,0,0] - 145 2022-05-23 18:37:51.654 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - version - {"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20211115} 2022-05-23 18:37:51.655 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.656 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Adapter concurrent: 2 2022-05-23 18:37:51.657 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Detected znp version 'zStack12' ({"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20211115}) 2022-05-23 18:37:51.660 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup beginning znp startup 2022-05-23 18:37:51.662 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - getExtAddr - {} 2022-05-23 18:37:51.663 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,4,37] 2022-05-23 18:37:51.675 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,8,97,4,217,2,217,20,0,75,18,0,34] 2022-05-23 18:37:51.676 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,8,97,4,217,2,217,20,0,75,18,0,34] 2022-05-23 18:37:51.677 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 8 - 3 - 1 - 4 - [217,2,217,20,0,75,18,0] - 34 2022-05-23 18:37:51.679 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - getExtAddr - {"extaddress":"0x00124b0014d902d9"} 2022-05-23 18:37:51.681 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.684 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":130} 2022-05-23 18:37:51.687 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,130,0,178] 2022-05-23 18:37:51.694 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,21,0,101] 2022-05-23 18:37:51.696 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,21,0,101] 2022-05-23 18:37:51.696 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [21,0] - 101 2022-05-23 18:37:51.698 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":21} 2022-05-23 18:37:51.699 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.700 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":130,"offset":0} 2022-05-23 18:37:51.701 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,130,0,0,0,187] 2022-05-23 18:37:51.710 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,23,97,28,0,21,0,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,105,111,0,0,122] 2022-05-23 18:37:51.712 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,23,97,28,0,21,0,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,105,111,0,0,122] 2022-05-23 18:37:51.712 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 23 - 3 - 1 - 28 - [0,21,0,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,105,111,0,0] - 122 2022-05-23 18:37:51.714 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":0,"len":21,"value":{"type":"Buffer","data":[0,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,105,111,0,0]}} 2022-05-23 18:37:51.715 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.718 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup:strategy determining znp startup strategy 2022-05-23 18:37:51.720 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":3840} 2022-05-23 18:37:51.721 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,0,15,63] 2022-05-23 18:37:51.729 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,1,0,113] 2022-05-23 18:37:51.740 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,1,0,113] 2022-05-23 18:37:51.740 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [1,0] - 113 2022-05-23 18:37:51.741 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":1} 2022-05-23 18:37:51.742 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.743 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":3840,"offset":0} 2022-05-23 18:37:51.744 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,0,15,0,0,54] 2022-05-23 18:37:51.757 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,97,28,0,1,85,42] 2022-05-23 18:37:51.758 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,97,28,0,1,85,42] 2022-05-23 18:37:51.759 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 3 - 1 - 28 - [0,1,85] - 42 2022-05-23 18:37:51.760 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":0,"len":1,"value":{"type":"Buffer","data":[85]}} 2022-05-23 18:37:51.761 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.764 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":33} 2022-05-23 18:37:51.765 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,33,0,17] 2022-05-23 18:37:51.772 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,110,0,30] 2022-05-23 18:37:51.773 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,110,0,30] 2022-05-23 18:37:51.774 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [110,0] - 30 2022-05-23 18:37:51.775 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":110} 2022-05-23 18:37:51.775 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.776 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":33,"offset":0} 2022-05-23 18:37:51.778 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,33,0,0,0,24] 2022-05-23 18:37:51.787 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,112,97,28,0,110,183,5,2,21,20,21,0,20,0,0,0,1,5,1,143,7,0,2,5,30,0,0,11,0,0,0,0,0,0,0,0,0,0,98,26,8,0,8,0,0,15,15,5,0,1,0,0,0,1,0,0,0,0,217,2,217,20,0,75,18,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,3,0,1,60,10,1,0,0,9,28,0,80] 2022-05-23 18:37:51.788 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,112,97,28,0,110,183,5,2,21,20,21,0,20,0,0,0,1,5,1,143,7,0,2,5,30,0,0,11,0,0,0,0,0,0,0,0,0,0,98,26,8,0,8,0,0,15,15,5,0,1,0,0,0,1,0,0,0,0,217,2,217,20,0,75,18,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,3,0,1,60,10,1,0,0,9,28,0,80] 2022-05-23 18:37:51.789 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 112 - 3 - 1 - 28 - [0,110,183,5,2,21,20,21,0,20,0,0,0,1,5,1,143,7,0,2,5,30,0,0,11,0,0,0,0,0,0,0,0,0,0,98,26,8,0,8,0,0,15,15,5,0,1,0,0,0,1,0,0,0,0,217,2,217,20,0,75,18,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,3,0,1,60,10,1,0,0,9,28,0] - 80 2022-05-23 18:37:51.790 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":0,"len":110,"value":{"type":"Buffer","data":[183,5,2,21,20,21,0,20,0,0,0,1,5,1,143,7,0,2,5,30,0,0,11,0,0,0,0,0,0,0,0,0,0,98,26,8,0,8,0,0,15,15,5,0,1,0,0,0,1,0,0,0,0,217,2,217,20,0,75,18,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,3,0,1,60,10,1,0,0,9,28,0]}} 2022-05-23 18:37:51.791 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.794 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SAPI - readConfiguration - {"configid":98} 2022-05-23 18:37:51.796 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,1,38,4,98,65] 2022-05-23 18:37:51.820 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,19,102,4,0,98,16,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,0] 2022-05-23 18:37:51.821 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,19,102,4,0,98,16,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,0] 2022-05-23 18:37:51.822 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 19 - 3 - 6 - 4 - [0,98,16,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13] - 0 2022-05-23 18:37:51.823 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SAPI - readConfiguration - {"status":0,"configid":98,"len":16,"value":{"type":"Buffer","data":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]}} 2022-05-23 18:37:51.824 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.825 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":58} 2022-05-23 18:37:51.826 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,58,0,10] 2022-05-23 18:37:51.832 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,17,0,97] 2022-05-23 18:37:51.833 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,17,0,97] 2022-05-23 18:37:51.834 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [17,0] - 97 2022-05-23 18:37:51.835 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":17} 2022-05-23 18:37:51.835 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.836 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":58,"offset":0} 2022-05-23 18:37:51.838 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,58,0,0,0,3] 2022-05-23 18:37:51.844 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,28,2,0,125] 2022-05-23 18:37:51.844 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,28,2,0,125] 2022-05-23 18:37:51.845 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 28 - [2,0] - 125 2022-05-23 18:37:51.846 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} 2022-05-23 18:37:51.847 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.848 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":58,"offset":0} 2022-05-23 18:37:51.850 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,58,0,0,0,3] 2022-05-23 18:37:51.856 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,28,2,0,125] 2022-05-23 18:37:51.857 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,28,2,0,125] 2022-05-23 18:37:51.858 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 28 - [2,0] - 125 2022-05-23 18:37:51.859 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} 2022-05-23 18:37:51.860 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.861 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":58,"offset":0} 2022-05-23 18:37:51.862 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,58,0,0,0,3] 2022-05-23 18:37:51.867 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,28,2,0,125] 2022-05-23 18:37:51.868 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,28,2,0,125] 2022-05-23 18:37:51.869 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 28 - [2,0] - 125 2022-05-23 18:37:51.870 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} 2022-05-23 18:37:51.871 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.872 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":59} 2022-05-23 18:37:51.873 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,59,0,11] 2022-05-23 18:37:51.880 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,17,0,97] 2022-05-23 18:37:51.881 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,17,0,97] 2022-05-23 18:37:51.881 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [17,0] - 97 2022-05-23 18:37:51.882 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":17} 2022-05-23 18:37:51.883 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.884 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":59,"offset":0} 2022-05-23 18:37:51.885 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,59,0,0,0,2] 2022-05-23 18:37:51.891 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,28,2,0,125] 2022-05-23 18:37:51.892 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,28,2,0,125] 2022-05-23 18:37:51.893 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 28 - [2,0] - 125 2022-05-23 18:37:51.893 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} 2022-05-23 18:37:51.894 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.895 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":59,"offset":0} 2022-05-23 18:37:51.896 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,59,0,0,0,2] 2022-05-23 18:37:51.902 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,28,2,0,125] 2022-05-23 18:37:51.903 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,28,2,0,125] 2022-05-23 18:37:51.904 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 28 - [2,0] - 125 2022-05-23 18:37:51.905 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} 2022-05-23 18:37:51.906 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.907 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":59,"offset":0} 2022-05-23 18:37:51.908 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,59,0,0,0,2] 2022-05-23 18:37:51.915 - silly: zigbee.1 (4854) States system redis pmessage system.adapter.zigbee.1.logLevel/system.adapter.zigbee.1.logLevel:{"val":"silly","ack":true,"ts":1653323871116,"q":0,"from":"system.adapter.zigbee.1","lc":1653323811764} 2022-05-23 18:37:51.920 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,28,2,0,125] 2022-05-23 18:37:51.921 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,28,2,0,125] 2022-05-23 18:37:51.922 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 28 - [2,0] - 125 2022-05-23 18:37:51.923 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} 2022-05-23 18:37:51.923 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.936 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup:strategy (stage-1) adapter is configured 2022-05-23 18:37:51.937 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup:strategy (stage-2) adapter state matches configuration 2022-05-23 18:37:51.938 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup determined startup strategy: startup 2022-05-23 18:37:51.939 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> UTIL - getDeviceInfo - {} 2022-05-23 18:37:51.940 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,39,0,39] 2022-05-23 18:37:51.948 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,103,0,0,217,2,217,20,0,75,18,0,0,0,7,9,0,40] 2022-05-23 18:37:51.948 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,103,0,0,217,2,217,20,0,75,18,0,0,0,7,9,0,40] 2022-05-23 18:37:51.949 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 3 - 7 - 0 - [0,217,2,217,20,0,75,18,0,0,0,7,9,0] - 40 2022-05-23 18:37:51.950 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b0014d902d9","shortaddr":0,"devicetype":7,"devicestate":9,"numassocdevices":0,"assocdeviceslist":[]} 2022-05-23 18:37:51.951 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.952 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup adapter is already running in coordinator mode 2022-05-23 18:37:51.953 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - activeEpReq - {"dstaddr":0,"nwkaddrofinterest":0} 2022-05-23 18:37:51.954 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,5,0,0,0,0,36] 2022-05-23 18:37:51.962 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,5,0,97] 2022-05-23 18:37:51.963 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,5,0,97] 2022-05-23 18:37:51.963 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 5 - [0] - 97 2022-05-23 18:37:51.964 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - activeEpReq - {"status":0} 2022-05-23 18:37:51.964 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.969 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,20,69,133,0,0,0,0,0,14,242,47,13,12,110,11,10,8,6,5,4,3,2,1,102] 2022-05-23 18:37:51.970 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,20,69,133,0,0,0,0,0,14,242,47,13,12,110,11,10,8,6,5,4,3,2,1,102] 2022-05-23 18:37:51.971 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 20 - 2 - 5 - 133 - [0,0,0,0,0,14,242,47,13,12,110,11,10,8,6,5,4,3,2,1] - 102 2022-05-23 18:37:51.972 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - activeEpRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"activeepcount":14,"activeeplist":[242,47,13,12,110,11,10,8,6,5,4,3,2,1]} 2022-05-23 18:37:51.973 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:51.974 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '1' already registered 2022-05-23 18:37:51.975 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '2' already registered 2022-05-23 18:37:51.975 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '3' already registered 2022-05-23 18:37:51.976 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '4' already registered 2022-05-23 18:37:51.977 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '5' already registered 2022-05-23 18:37:51.977 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '6' already registered 2022-05-23 18:37:51.978 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '8' already registered 2022-05-23 18:37:51.978 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '10' already registered 2022-05-23 18:37:51.979 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '11' already registered 2022-05-23 18:37:51.980 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '110' already registered 2022-05-23 18:37:51.980 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '12' already registered 2022-05-23 18:37:51.981 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '13' already registered 2022-05-23 18:37:51.982 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '47' already registered 2022-05-23 18:37:51.982 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:startup endpoint '242' already registered 2022-05-23 18:37:51.984 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extFindGroup - {"endpoint":242,"groupid":2948} 2022-05-23 18:37:51.985 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,37,74,242,132,11,17] 2022-05-23 18:37:52.025 - debug: zigbee.1 (4854) zigbee-herdsman:adapter: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] 2022-05-23 18:37:52.026 - debug: zigbee.1 (4854) zigbee-herdsman:adapter: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] 2022-05-23 18:37:52.026 - debug: zigbee.1 (4854) zigbee-herdsman:adapter: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 2022-05-23 18:37:52.027 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extFindGroup - {"status":0,"groupid":2948,"namelen":0,"groupname":{"type":"Buffer","data":[]}} 2022-05-23 18:37:52.028 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.029 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Started with result 'resumed' 2022-05-23 18:37:52.030 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Injected database: true, adapter: true 2022-05-23 18:37:52.032 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - activeEpReq - {"dstaddr":0,"nwkaddrofinterest":0} 2022-05-23 18:37:52.033 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,5,0,0,0,0,36] 2022-05-23 18:37:52.041 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,5,0,97] 2022-05-23 18:37:52.041 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,5,0,97] 2022-05-23 18:37:52.042 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 5 - [0] - 97 2022-05-23 18:37:52.043 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - activeEpReq - {"status":0} 2022-05-23 18:37:52.044 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.049 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,20,69,133,0,0,0,0,0,14,242,47,13,12,110,11,10,8,6,5,4,3,2,1,102] 2022-05-23 18:37:52.050 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,20,69,133,0,0,0,0,0,14,242,47,13,12,110,11,10,8,6,5,4,3,2,1,102] 2022-05-23 18:37:52.051 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 20 - 2 - 5 - 133 - [0,0,0,0,0,14,242,47,13,12,110,11,10,8,6,5,4,3,2,1] - 102 2022-05-23 18:37:52.052 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - activeEpRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"activeepcount":14,"activeeplist":[242,47,13,12,110,11,10,8,6,5,4,3,2,1]} 2022-05-23 18:37:52.052 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.053 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> UTIL - getDeviceInfo - {} 2022-05-23 18:37:52.054 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,39,0,39] 2022-05-23 18:37:52.062 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,103,0,0,217,2,217,20,0,75,18,0,0,0,7,9,0,40] 2022-05-23 18:37:52.063 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,103,0,0,217,2,217,20,0,75,18,0,0,0,7,9,0,40] 2022-05-23 18:37:52.063 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 3 - 7 - 0 - [0,217,2,217,20,0,75,18,0,0,0,7,9,0] - 40 2022-05-23 18:37:52.064 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b0014d902d9","shortaddr":0,"devicetype":7,"devicestate":9,"numassocdevices":0,"assocdeviceslist":[]} 2022-05-23 18:37:52.065 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.066 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":242} 2022-05-23 18:37:52.067 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,242,214] 2022-05-23 18:37:52.074 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.075 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.076 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.077 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.078 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.083 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,242,224,161,5,0,0,0,0,113] 2022-05-23 18:37:52.083 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,242,224,161,5,0,0,0,0,113] 2022-05-23 18:37:52.084 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,242,224,161,5,0,0,0,0] - 113 2022-05-23 18:37:52.085 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":242,"profileid":41440,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.086 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.087 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":47} 2022-05-23 18:37:52.088 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,47,11] 2022-05-23 18:37:52.095 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.096 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.097 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.098 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.099 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.104 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,47,4,1,5,0,0,0,0,232] 2022-05-23 18:37:52.104 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,47,4,1,5,0,0,0,0,232] 2022-05-23 18:37:52.105 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,47,4,1,5,0,0,0,0] - 232 2022-05-23 18:37:52.106 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":47,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.107 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.108 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":13} 2022-05-23 18:37:52.109 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,13,41] 2022-05-23 18:37:52.117 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.118 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.118 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.119 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.120 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.125 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,16,69,132,0,0,0,0,0,10,13,4,1,5,0,0,1,25,0,0,206] 2022-05-23 18:37:52.126 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,16,69,132,0,0,0,0,0,10,13,4,1,5,0,0,1,25,0,0,206] 2022-05-23 18:37:52.127 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 16 - 2 - 5 - 132 - [0,0,0,0,0,10,13,4,1,5,0,0,1,25,0,0] - 206 2022-05-23 18:37:52.127 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":10,"endpoint":13,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":1,"inclusterlist":[25],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.129 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.130 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":12} 2022-05-23 18:37:52.130 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,12,40] 2022-05-23 18:37:52.138 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.139 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.139 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.140 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.141 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.146 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,12,94,192,5,0,0,0,0,80] 2022-05-23 18:37:52.146 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,12,94,192,5,0,0,0,0,80] 2022-05-23 18:37:52.147 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,12,94,192,5,0,0,0,0] - 80 2022-05-23 18:37:52.148 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":12,"profileid":49246,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.149 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.150 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":110} 2022-05-23 18:37:52.150 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,110,74] 2022-05-23 18:37:52.158 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.159 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.159 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.160 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.161 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.166 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,110,4,1,5,0,0,0,0,169] 2022-05-23 18:37:52.167 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,110,4,1,5,0,0,0,0,169] 2022-05-23 18:37:52.168 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,110,4,1,5,0,0,0,0] - 169 2022-05-23 18:37:52.168 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":110,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.169 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.170 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":11} 2022-05-23 18:37:52.171 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,11,47] 2022-05-23 18:37:52.179 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.180 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.180 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.181 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.182 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.188 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,22,69,132,0,0,0,0,0,16,11,4,1,0,4,0,2,1,5,10,0,2,0,5,2,5,193] 2022-05-23 18:37:52.189 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,22,69,132,0,0,0,0,0,16,11,4,1,0,4,0,2,1,5,10,0,2,0,5,2,5,193] 2022-05-23 18:37:52.190 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 22 - 2 - 5 - 132 - [0,0,0,0,0,16,11,4,1,0,4,0,2,1,5,10,0,2,0,5,2,5] - 193 2022-05-23 18:37:52.190 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":16,"endpoint":11,"profileid":260,"deviceid":1024,"deviceversion":0,"numinclusters":2,"inclusterlist":[1281,10],"numoutclusters":2,"outclusterlist":[1280,1282]} 2022-05-23 18:37:52.191 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.192 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":10} 2022-05-23 18:37:52.193 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,10,46] 2022-05-23 18:37:52.200 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.201 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.201 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.202 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.203 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.207 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,10,4,1,5,0,0,0,0,205] 2022-05-23 18:37:52.208 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,10,4,1,5,0,0,0,0,205] 2022-05-23 18:37:52.208 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,10,4,1,5,0,0,0,0] - 205 2022-05-23 18:37:52.209 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":10,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.210 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.211 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":8} 2022-05-23 18:37:52.212 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,8,44] 2022-05-23 18:37:52.219 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.220 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.220 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.221 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.221 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.226 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,8,4,1,5,0,0,0,0,207] 2022-05-23 18:37:52.227 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,8,4,1,5,0,0,0,0,207] 2022-05-23 18:37:52.228 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,8,4,1,5,0,0,0,0] - 207 2022-05-23 18:37:52.229 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":8,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.230 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.230 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":6} 2022-05-23 18:37:52.231 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,6,34] 2022-05-23 18:37:52.238 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.239 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.240 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.241 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.241 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.244 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,6,9,1,5,0,0,0,0,204] 2022-05-23 18:37:52.245 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,6,9,1,5,0,0,0,0,204] 2022-05-23 18:37:52.246 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,6,9,1,5,0,0,0,0] - 204 2022-05-23 18:37:52.247 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":6,"profileid":265,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.247 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.248 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":5} 2022-05-23 18:37:52.249 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,5,33] 2022-05-23 18:37:52.256 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.257 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.257 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.258 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.259 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.266 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,5,8,1,5,0,0,0,0,206] 2022-05-23 18:37:52.269 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,5,8,1,5,0,0,0,0,206] 2022-05-23 18:37:52.270 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,5,8,1,5,0,0,0,0] - 206 2022-05-23 18:37:52.271 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":5,"profileid":264,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.271 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.273 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":4} 2022-05-23 18:37:52.274 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,4,32] 2022-05-23 18:37:52.285 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.286 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.287 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.287 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.288 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.291 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,4,7,1,5,0,0,0,0,192] 2022-05-23 18:37:52.292 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,4,7,1,5,0,0,0,0,192] 2022-05-23 18:37:52.293 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,4,7,1,5,0,0,0,0] - 192 2022-05-23 18:37:52.293 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":4,"profileid":263,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.294 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.295 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":3} 2022-05-23 18:37:52.295 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,3,39] 2022-05-23 18:37:52.303 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.303 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.304 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.304 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.305 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.312 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,3,4,1,5,0,0,0,0,196] 2022-05-23 18:37:52.312 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,3,4,1,5,0,0,0,0,196] 2022-05-23 18:37:52.313 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,3,4,1,5,0,0,0,0] - 196 2022-05-23 18:37:52.314 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":3,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.314 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.315 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":2} 2022-05-23 18:37:52.316 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,2,38] 2022-05-23 18:37:52.326 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.327 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.327 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.328 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.328 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.331 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,2,1,1,5,0,0,0,0,192] 2022-05-23 18:37:52.332 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,2,1,1,5,0,0,0,0,192] 2022-05-23 18:37:52.333 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,2,1,1,5,0,0,0,0] - 192 2022-05-23 18:37:52.333 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":2,"profileid":257,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.334 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.335 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":1} 2022-05-23 18:37:52.335 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,1,37] 2022-05-23 18:37:52.343 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] 2022-05-23 18:37:52.343 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] 2022-05-23 18:37:52.344 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 2022-05-23 18:37:52.344 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} 2022-05-23 18:37:52.345 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.348 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,1,4,1,5,0,0,0,0,198] 2022-05-23 18:37:52.349 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,1,4,1,5,0,0,0,0,198] 2022-05-23 18:37:52.350 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,1,4,1,5,0,0,0,0] - 198 2022-05-23 18:37:52.350 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":1,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} 2022-05-23 18:37:52.351 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.355 - debug: zigbee.1 (4854) zigbee-herdsman started 2022-05-23 18:37:52.356 - info: zigbee.1 (4854) Coordinator firmware version: {"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20211115}} 2022-05-23 18:37:52.357 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extNwkInfo - {} 2022-05-23 18:37:52.358 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,37,80,117] 2022-05-23 18:37:52.362 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,24,101,80,0,0,9,98,26,0,0,217,2,217,20,0,75,18,0,0,0,0,0,0,0,0,0,11,24] 2022-05-23 18:37:52.363 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,24,101,80,0,0,9,98,26,0,0,217,2,217,20,0,75,18,0,0,0,0,0,0,0,0,0,11,24] 2022-05-23 18:37:52.363 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 24 - 3 - 5 - 80 - [0,0,9,98,26,0,0,217,2,217,20,0,75,18,0,0,0,0,0,0,0,0,0,11] - 24 2022-05-23 18:37:52.364 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extNwkInfo - {"shortaddr":0,"devstate":9,"panid":6754,"parentaddr":0,"extendedpanid":"0x00124b0014d902d9","parentextaddr":"0x0000000000000000","channel":11} 2022-05-23 18:37:52.365 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.365 - debug: zigbee.1 (4854) Zigbee network parameters: panID=6754 channel=11 extendedPanID=d902d914004b1200 2022-05-23 18:37:52.366 - info: zigbee.1 (4854) Unable to disable LED, unsupported function. 2022-05-23 18:37:52.367 - info: zigbee.1 (4854) --> transmitPower : high+ 2022-05-23 18:37:52.369 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - stackTune - {"operation":0,"value":"20"} 2022-05-23 18:37:52.370 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,15,0,20,56] 2022-05-23 18:37:52.374 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,97,15,0,111] 2022-05-23 18:37:52.375 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,97,15,0,111] 2022-05-23 18:37:52.375 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 1 - 15 - [0] - 111 2022-05-23 18:37:52.376 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - stackTune - {"value":0} 2022-05-23 18:37:52.377 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:37:52.379 - info: zigbee.1 (4854) Currently no devices. 2022-05-23 18:37:52.380 - info: zigbee.1 (4854) Zigbee started 2022-05-23 18:37:53.091 - info: zigbee.1 (4854) Installed Version: iobroker.zigbee@1.6.18 2022-05-23 18:37:53.565 - debug: zigbee.1 (4854) getGroups result: {} 2022-05-23 18:37:53.595 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.connection:{"val":false,"ack":true,"ts":1653323873569,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323873569} 2022-05-23 18:37:53.753 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.connection:{"val":true,"ack":true,"ts":1653323873590,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323813121} 2022-05-23 18:37:53.924 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.undefinedDevices:{"val":"{}","ack":true,"ts":1653323873910,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323873910} 2022-05-23 18:38:07.540 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"listUart","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":466,"ack":false,"time":1653323887538},"_id":4917624} 2022-05-23 18:38:07.561 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getCoordinatorInfo","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":467,"ack":false,"time":1653323887539},"_id":4917625} 2022-05-23 18:38:07.564 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getDevices","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":468,"ack":false,"time":1653323887540},"_id":4917626} 2022-05-23 18:38:07.566 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getMap","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":469,"ack":false,"time":1653323887540},"_id":4917627} 2022-05-23 18:38:07.571 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtLqiReq - {"dstaddr":0,"startindex":0} 2022-05-23 18:38:07.572 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,37,49,0,0,0,23] 2022-05-23 18:38:07.573 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getGroups","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":470,"ack":false,"time":1653323887541},"_id":4917628} 2022-05-23 18:38:07.574 - debug: zigbee.1 (4854) getGroups result: {} 2022-05-23 18:38:07.575 - silly: zigbee.1 (4854) sendTo "getGroups" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:07.576 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getLibData","message":{"key":"cidList"},"from":"system.adapter.admin.0","callback":{"message":{"key":"cidList"},"id":471,"ack":false,"time":1653323887541},"_id":4917629} 2022-05-23 18:38:07.577 - silly: zigbee.1 (4854) sendTo "getLibData" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:07.596 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,49,0,85] 2022-05-23 18:38:07.597 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,49,0,85] 2022-05-23 18:38:07.597 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 49 - [0] - 85 2022-05-23 18:38:07.598 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtLqiReq - {"status":0} 2022-05-23 18:38:07.599 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:07.601 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,72,69,177,0,0,0,5,0,3,217,2,217,20,0,75,18,0,107,114,33,254,255,236,134,204,223,227,57,2,255,44,217,2,217,20,0,75,18,0,93,28,208,254,255,39,253,132,123,128,57,2,255,84,217,2,217,20,0,75,18,0,99,146,23,254,255,236,134,204,240,61,57,2,255,1,82] 2022-05-23 18:38:07.618 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,72,69,177,0,0,0,5,0,3,217,2,217,20,0,75,18,0,107,114,33,254,255,236,134,204,223,227,57,2,255,44,217,2,217,20,0,75,18,0,93,28,208,254,255,39,253,132,123,128,57,2,255,84,217,2,217,20,0,75,18,0,99,146,23,254,255,236,134,204,240,61,57,2,255,1,82] 2022-05-23 18:38:07.619 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 72 - 2 - 5 - 177 - [0,0,0,5,0,3,217,2,217,20,0,75,18,0,107,114,33,254,255,236,134,204,223,227,57,2,255,44,217,2,217,20,0,75,18,0,93,28,208,254,255,39,253,132,123,128,57,2,255,84,217,2,217,20,0,75,18,0,99,146,23,254,255,236,134,204,240,61,57,2,255,1] - 82 2022-05-23 18:38:07.621 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtLqiRsp - {"srcaddr":0,"status":0,"neighbortableentries":5,"startindex":0,"neighborlqilistcount":3,"neighborlqilist":[{"extPandId":"0x00124b0014d902d9","extAddr":"0xcc86ecfffe21726b","nwkAddr":58335,"deviceType":1,"rxOnWhenIdle":2,"relationship":3,"permitJoin":2,"depth":255,"lqi":44},{"extPandId":"0x00124b0014d902d9","extAddr":"0x84fd27fffed01c5d","nwkAddr":32891,"deviceType":1,"rxOnWhenIdle":2,"relationship":3,"permitJoin":2,"depth":255,"lqi":84},{"extPandId":"0x00124b0014d902d9","extAddr":"0xcc86ecfffe179263","nwkAddr":15856,"deviceType":1,"rxOnWhenIdle":2,"relationship":3,"permitJoin":2,"depth":255,"lqi":1}]} 2022-05-23 18:38:07.622 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:07.623 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtLqiReq - {"dstaddr":0,"startindex":3} 2022-05-23 18:38:07.623 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,37,49,0,0,3,20] 2022-05-23 18:38:07.632 - debug: zigbee.1 (4854) getCoorinatorInfo result: {"installSource":"iobroker.zigbee@1.6.18","channel":"11","port":"/dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0014D902D9-if00","type":"zStack12","revision":20211115,"version":"2-0.2.6.3."} 2022-05-23 18:38:07.633 - silly: zigbee.1 (4854) sendTo "getCoordinatorInfo" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:07.634 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,49,0,85] 2022-05-23 18:38:07.635 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,49,0,85] 2022-05-23 18:38:07.636 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 49 - [0] - 85 2022-05-23 18:38:07.637 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtLqiReq - {"status":0} 2022-05-23 18:38:07.637 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:07.643 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,50,69,177,0,0,0,5,3,2,217,2,217,20,0,75,18,0,185,225,196,254,255,87,180,20,191,131,57,2,255,16,217,2,217,20,0,75,18,0,239,112,185,254,255,129,142,88,162,168,57,2,255,0,254] 2022-05-23 18:38:07.644 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,50,69,177,0,0,0,5,3,2,217,2,217,20,0,75,18,0,185,225,196,254,255,87,180,20,191,131,57,2,255,16,217,2,217,20,0,75,18,0,239,112,185,254,255,129,142,88,162,168,57,2,255,0,254] 2022-05-23 18:38:07.645 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 50 - 2 - 5 - 177 - [0,0,0,5,3,2,217,2,217,20,0,75,18,0,185,225,196,254,255,87,180,20,191,131,57,2,255,16,217,2,217,20,0,75,18,0,239,112,185,254,255,129,142,88,162,168,57,2,255,0] - 254 2022-05-23 18:38:07.646 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtLqiRsp - {"srcaddr":0,"status":0,"neighbortableentries":5,"startindex":3,"neighborlqilistcount":2,"neighborlqilist":[{"extPandId":"0x00124b0014d902d9","extAddr":"0x14b457fffec4e1b9","nwkAddr":33727,"deviceType":1,"rxOnWhenIdle":2,"relationship":3,"permitJoin":2,"depth":255,"lqi":16},{"extPandId":"0x00124b0014d902d9","extAddr":"0x588e81fffeb970ef","nwkAddr":43170,"deviceType":1,"rxOnWhenIdle":2,"relationship":3,"permitJoin":2,"depth":255,"lqi":0}]} 2022-05-23 18:38:07.646 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:07.647 - debug: zigbee.1 (4854) LQI succeeded for 'Coordinator' 2022-05-23 18:38:07.650 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtRtgReq - {"dstaddr":0,"startindex":0} 2022-05-23 18:38:07.650 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,37,50,0,0,0,20] 2022-05-23 18:38:07.658 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,50,0,86] 2022-05-23 18:38:07.658 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,50,0,86] 2022-05-23 18:38:07.659 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 50 - [0] - 86 2022-05-23 18:38:07.660 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtRtgReq - {"status":0} 2022-05-23 18:38:07.660 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:07.666 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,56,69,178,0,0,0,10,0,10,241,7,0,123,128,130,154,0,123,128,162,168,0,123,128,170,220,0,223,227,223,227,0,123,128,240,61,0,123,128,234,96,0,223,227,129,191,0,123,128,47,81,0,123,128,200,191,0,123,128,17] 2022-05-23 18:38:07.667 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,56,69,178,0,0,0,10,0,10,241,7,0,123,128,130,154,0,123,128,162,168,0,123,128,170,220,0,223,227,223,227,0,123,128,240,61,0,123,128,234,96,0,223,227,129,191,0,123,128,47,81,0,123,128,200,191,0,123,128,17] 2022-05-23 18:38:07.667 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 56 - 2 - 5 - 178 - [0,0,0,10,0,10,241,7,0,123,128,130,154,0,123,128,162,168,0,123,128,170,220,0,223,227,223,227,0,123,128,240,61,0,123,128,234,96,0,223,227,129,191,0,123,128,47,81,0,123,128,200,191,0,123,128] - 17 2022-05-23 18:38:07.668 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtRtgRsp - {"srcaddr":0,"status":0,"routingtableentries":10,"startindex":0,"routingtablelistcount":10,"routingtablelist":[{"destNwkAddr":2033,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":39554,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":43170,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":56490,"routeStatus":"ACTIVE","nextHopNwkAddr":58335},{"destNwkAddr":58335,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":15856,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":24810,"routeStatus":"ACTIVE","nextHopNwkAddr":58335},{"destNwkAddr":49025,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":20783,"routeStatus":"ACTIVE","nextHopNwkAddr":32891},{"destNwkAddr":49096,"routeStatus":"ACTIVE","nextHopNwkAddr":32891}]} 2022-05-23 18:38:07.669 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:07.671 - debug: zigbee.1 (4854) Routing for 'Coordinator': {"table":[{"destinationAddress":2033,"status":"ACTIVE","nextHop":32891},{"destinationAddress":39554,"status":"ACTIVE","nextHop":32891},{"destinationAddress":43170,"status":"ACTIVE","nextHop":32891},{"destinationAddress":56490,"status":"ACTIVE","nextHop":58335},{"destinationAddress":58335,"status":"ACTIVE","nextHop":32891},{"destinationAddress":15856,"status":"ACTIVE","nextHop":32891},{"destinationAddress":24810,"status":"ACTIVE","nextHop":58335},{"destinationAddress":49025,"status":"ACTIVE","nextHop":32891},{"destinationAddress":20783,"status":"ACTIVE","nextHop":32891},{"destinationAddress":49096,"status":"ACTIVE","nextHop":32891}]} 2022-05-23 18:38:07.672 - debug: zigbee.1 (4854) Routing table succeeded for 'Coordinator' 2022-05-23 18:38:07.673 - debug: zigbee.1 (4854) Get map succeeded [{"parent":"0x00124b0014d902d9","networkAddress":58335,"ieeeAddr":"0xcc86ecfffe21726b","lqi":44,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":32891,"ieeeAddr":"0x84fd27fffed01c5d","lqi":84,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":15856,"ieeeAddr":"0xcc86ecfffe179263","lqi":1,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":33727,"ieeeAddr":"0x14b457fffec4e1b9","lqi":16,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":43170,"ieeeAddr":"0x588e81fffeb970ef","lqi":0,"relationship":3,"depth":255,"status":"offline"}] 2022-05-23 18:38:07.673 - debug: zigbee.1 (4854) getMap result: {"lqis":[{"parent":"0x00124b0014d902d9","networkAddress":58335,"ieeeAddr":"0xcc86ecfffe21726b","lqi":44,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":32891,"ieeeAddr":"0x84fd27fffed01c5d","lqi":84,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":15856,"ieeeAddr":"0xcc86ecfffe179263","lqi":1,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":33727,"ieeeAddr":"0x14b457fffec4e1b9","lqi":16,"relationship":3,"depth":255,"status":"online"},{"parent":"0x00124b0014d902d9","networkAddress":43170,"ieeeAddr":"0x588e81fffeb970ef","lqi":0,"relationship":3,"depth":255,"status":"offline"}],"routing":[{"source":"0x00124b0014d902d9","destination":2033,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":39554,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":43170,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":56490,"nextHop":58335,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":58335,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":15856,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":24810,"nextHop":58335,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":49025,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":20783,"nextHop":32891,"status":"ACTIVE"},{"source":"0x00124b0014d902d9","destination":49096,"nextHop":32891,"status":"ACTIVE"}]} 2022-05-23 18:38:07.674 - silly: zigbee.1 (4854) sendTo "getMap" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:07.822 - debug: zigbee.1 (4854) List of ports: [{"comName":"/dev/ttyUSB0"},{"comName":"/dev/ttyACM0"},{"comName":"/dev/ttyAMA0"},{"comName":"/dev/ttyS0"}] 2022-05-23 18:38:07.823 - silly: zigbee.1 (4854) sendTo "listUart" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:07.901 - debug: zigbee.1 (4854) getDevices result: [{"_id":"0x00124b0014d902d9","icon":"img/unknown.png","paired":true,"info":{"type":"device","device":{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":1,"_type":"Coordinator","_ieeeAddr":"0x00124b0014d902d9","_networkAddress":0,"_manufacturerID":0,"_endpoints":[{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":1,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":2,"profileID":257,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":3,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":4,"profileID":263,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":5,"profileID":264,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":6,"profileID":265,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":8,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":10,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":11,"profileID":260,"deviceID":1024,"inputClusters":[1281,10],"outputClusters":[1280,1282],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":12,"profileID":49246,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":13,"profileID":260,"deviceID":5,"inputClusters":[25],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":47,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":110,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":242,"profileID":41440,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]}],"_interviewCompleted":true,"_interviewing":false,"_skipDefaultResponse":false,"_skipTimeResponse":false,"meta":{},"_lastSeen":null,"_defaultSendRequestWhen":"immediate"},"mapped":null,"endpoint":{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":1,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},"endpoints":[{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":1,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":2,"profileID":257,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":3,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":4,"profileID":263,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":5,"profileID":264,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":6,"profileID":265,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":8,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":10,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":11,"profileID":260,"deviceID":1024,"inputClusters":[1281,10],"outputClusters":[1280,1282],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":12,"profileID":49246,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":13,"profileID":260,"deviceID":5,"inputClusters":[25],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":47,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":110,"profileID":260,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]},{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":242,"profileID":41440,"deviceID":5,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":0,"deviceIeeeAddress":"0x00124b0014d902d9","clusters":{},"_binds":[],"_configuredReportings":[],"meta":{},"pendingRequests":[]}],"name":"0x00124b0014d902d9"},"common":{},"native":{}}] 2022-05-23 18:38:07.903 - silly: zigbee.1 (4854) sendTo "getDevices" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:07.936 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getExclude","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":472,"ack":false,"time":1653323887935},"_id":4917630} 2022-05-23 18:38:07.939 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"getBinding","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":473,"ack":false,"time":1653323887936},"_id":4917631} 2022-05-23 18:38:08.013 - debug: zigbee.1 (4854) getExclude result: [] 2022-05-23 18:38:08.016 - debug: zigbee.1 (4854) getBinding result: [] 2022-05-23 18:38:08.017 - silly: zigbee.1 (4854) sendTo "getBinding" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:08.064 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.exclude.all:{"val":"[]","ack":true,"ts":1653323888060,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653266443428} 2022-05-23 18:38:08.066 - silly: zigbee.1 (4854) sendTo "getExclude" to system.adapter.admin.0 from system.adapter.zigbee.1 2022-05-23 18:38:10.448 - silly: zigbee.1 (4854) States system redis pmessage io.messagebox.system.adapter.zigbee.1/io.messagebox.system.adapter.zigbee.1:{"command":"letsPairing","message":{},"from":"system.adapter.admin.0","callback":{"message":{},"id":474,"ack":false,"time":1653323890447},"_id":4917632} 2022-05-23 18:38:10.449 - info: zigbee.1 (4854) Zigbee: allowing new devices to join. 2022-05-23 18:38:10.451 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtPermitJoinReq - {"addrmode":15,"dstaddr":65532,"duration":254,"tcsignificance":0} 2022-05-23 18:38:10.452 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,54,15,252,255,254,0,228] 2022-05-23 18:38:10.452 - silly: zigbee.1 (4854) sendTo "letsPairing" to system.adapter.admin.0 from system.adapter.zigbee.1: Start pairing! 2022-05-23 18:38:10.465 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing started ","ack":true,"ts":1653323890461,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323890461} 2022-05-23 18:38:10.468 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,54,0,82] 2022-05-23 18:38:10.469 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,54,0,82] 2022-05-23 18:38:10.470 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 54 - [0] - 82 2022-05-23 18:38:10.470 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtPermitJoinReq - {"status":0} 2022-05-23 18:38:10.471 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:10.476 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequestExt - {"dstaddrmode":2,"dstaddr":"0x000000000000fffd","destendpoint":242,"dstpanid":0,"srcendpoint":242,"clusterid":33,"transid":1,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[25,2,2,11,254,0]}} 2022-05-23 18:38:10.477 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,26,36,2,2,253,255,0,0,0,0,0,0,242,0,0,242,33,0,1,0,30,6,0,25,2,2,11,254,0,232] 2022-05-23 18:38:10.483 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,69,203,254,113,254,3,69,182,0,0,0,240] 2022-05-23 18:38:10.484 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,69,203,254,113,254,3,69,182,0,0,0,240] 2022-05-23 18:38:10.485 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 2 - 5 - 203 - [254] - 113 2022-05-23 18:38:10.487 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - permitJoinInd - {"duration":254} 2022-05-23 18:38:10.488 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,182,0,0,0,240] 2022-05-23 18:38:10.488 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 182 - [0,0,0] - 240 2022-05-23 18:38:10.490 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtPermitJoinRsp - {"srcaddr":0,"status":0} 2022-05-23 18:38:10.490 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:10.497 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,2,0,103] 2022-05-23 18:38:10.498 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,2,0,103] 2022-05-23 18:38:10.500 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 2 - [0] - 103 2022-05-23 18:38:10.501 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequestExt - {"status":0} 2022-05-23 18:38:10.502 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:10.508 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,242,1,52] 2022-05-23 18:38:10.515 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,242,1,52] 2022-05-23 18:38:10.516 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,242,1] - 52 2022-05-23 18:38:10.516 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":242,"transid":1} 2022-05-23 18:38:10.517 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:11.718 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":60,"ack":true,"ts":1653323891709,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323891709} 2022-05-23 18:38:11.723 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 60","ack":true,"ts":1653323891710,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323891710} 2022-05-23 18:38:12.716 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":59,"ack":true,"ts":1653323892710,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323892710} 2022-05-23 18:38:12.720 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 59","ack":true,"ts":1653323892711,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323892711} 2022-05-23 18:38:13.713 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":58,"ack":true,"ts":1653323893710,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323893710} 2022-05-23 18:38:13.717 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 58","ack":true,"ts":1653323893710,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323893710} 2022-05-23 18:38:14.716 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":57,"ack":true,"ts":1653323894710,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323894710} 2022-05-23 18:38:14.720 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 57","ack":true,"ts":1653323894711,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323894711} 2022-05-23 18:38:15.716 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":56,"ack":true,"ts":1653323895712,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323895712} 2022-05-23 18:38:15.720 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 56","ack":true,"ts":1653323895712,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323895712} 2022-05-23 18:38:16.718 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":55,"ack":true,"ts":1653323896713,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323896713} 2022-05-23 18:38:16.722 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 55","ack":true,"ts":1653323896713,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323896713} 2022-05-23 18:38:17.717 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":54,"ack":true,"ts":1653323897713,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323897713} 2022-05-23 18:38:17.722 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 54","ack":true,"ts":1653323897714,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323897714} 2022-05-23 18:38:18.722 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":53,"ack":true,"ts":1653323898716,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323898716} 2022-05-23 18:38:18.727 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 53","ack":true,"ts":1653323898716,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323898716} 2022-05-23 18:38:19.719 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":52,"ack":true,"ts":1653323899715,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323899715} 2022-05-23 18:38:19.722 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 52","ack":true,"ts":1653323899715,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323899715} 2022-05-23 18:38:20.746 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":51,"ack":true,"ts":1653323900740,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323900740} 2022-05-23 18:38:20.750 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 51","ack":true,"ts":1653323900740,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323900740} 2022-05-23 18:38:21.721 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":50,"ack":true,"ts":1653323901717,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323901717} 2022-05-23 18:38:21.725 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 50","ack":true,"ts":1653323901717,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323901717} 2022-05-23 18:38:22.721 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":49,"ack":true,"ts":1653323902717,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323902717} 2022-05-23 18:38:22.726 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 49","ack":true,"ts":1653323902717,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323902717} 2022-05-23 18:38:23.724 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":48,"ack":true,"ts":1653323903719,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323903719} 2022-05-23 18:38:23.729 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 48","ack":true,"ts":1653323903720,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323903720} 2022-05-23 18:38:24.016 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,123,128,0,121] 2022-05-23 18:38:24.017 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,123,128,0,121] 2022-05-23 18:38:24.018 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [123,128,0] - 121 2022-05-23 18:38:24.019 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":32891,"relaycount":0,"relaylist":[]} 2022-05-23 18:38:24.020 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:24.026 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,123,128,0,121] 2022-05-23 18:38:24.027 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,123,128,0,121] 2022-05-23 18:38:24.028 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [123,128,0] - 121 2022-05-23 18:38:24.029 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":32891,"relaycount":0,"relaylist":[]} 2022-05-23 18:38:24.045 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:24.098 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,47,81,34,49,117,254,255,249,227,180,123,128,207] 2022-05-23 18:38:24.099 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,47,81,34,49,117,254,255,249,227,180,123,128,207] 2022-05-23 18:38:24.099 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [47,81,34,49,117,254,255,249,227,180,123,128] - 207 2022-05-23 18:38:24.100 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":20783,"extaddr":"0xb4e3f9fffe753122","parentaddr":32891} 2022-05-23 18:38:24.102 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Device '0xb4e3f9fffe753122' joined 2022-05-23 18:38:24.102 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log New device '0xb4e3f9fffe753122' joined 2022-05-23 18:38:24.103 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Creating device '0xb4e3f9fffe753122' 2022-05-23 18:38:24.107 - debug: zigbee.1 (4854) handleDeviceJoined. {"device":{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":2,"_type":"Unknown","_ieeeAddr":"0xb4e3f9fffe753122","_networkAddress":20783,"_endpoints":[],"_interviewCompleted":false,"_interviewing":false,"_skipDefaultResponse":false,"_skipTimeResponse":false,"meta":{},"_lastSeen":null,"_defaultSendRequestWhen":"immediate"}} 2022-05-23 18:38:24.108 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Interview '0xb4e3f9fffe753122' start 2022-05-23 18:38:24.110 - debug: zigbee.1 (4854) handleDeviceInterview. {"status":"started","device":{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":2,"_type":"Unknown","_ieeeAddr":"0xb4e3f9fffe753122","_networkAddress":20783,"_endpoints":[],"_interviewCompleted":false,"_interviewing":false,"_skipDefaultResponse":false,"_skipTimeResponse":false,"meta":{},"_lastSeen":1653323904108,"_defaultSendRequestWhen":"immediate"}} 2022-05-23 18:38:24.111 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - start device '0xb4e3f9fffe753122' 2022-05-23 18:38:24.115 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:38:24.116 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:38:24.116 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:24.117 - info: zigbee.1 (4854) Starting interview of '0xb4e3f9fffe753122' 2022-05-23 18:38:24.136 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:38:24.137 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:38:24.138 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:38:24.139 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:38:24.139 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:24.203 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Interview started: 0xb4e3f9fffe753122","ack":true,"ts":1653323904198,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323904198} 2022-05-23 18:38:24.723 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":47,"ack":true,"ts":1653323904718,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323904718} 2022-05-23 18:38:24.727 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 47","ack":true,"ts":1653323904718,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323904718} 2022-05-23 18:38:25.746 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":46,"ack":true,"ts":1653323905732,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323905732} 2022-05-23 18:38:25.750 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 46","ack":true,"ts":1653323905732,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323905732} 2022-05-23 18:38:26.727 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":45,"ack":true,"ts":1653323906719,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323906719} 2022-05-23 18:38:26.731 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 45","ack":true,"ts":1653323906720,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323906720} 2022-05-23 18:38:27.728 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":44,"ack":true,"ts":1653323907722,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323907722} 2022-05-23 18:38:27.730 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 44","ack":true,"ts":1653323907725,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323907725} 2022-05-23 18:38:28.724 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":43,"ack":true,"ts":1653323908721,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323908721} 2022-05-23 18:38:28.728 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 43","ack":true,"ts":1653323908721,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323908721} 2022-05-23 18:38:29.743 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":42,"ack":true,"ts":1653323909739,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323909739} 2022-05-23 18:38:29.749 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 42","ack":true,"ts":1653323909739,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323909739} 2022-05-23 18:38:30.727 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":41,"ack":true,"ts":1653323910722,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323910722} 2022-05-23 18:38:30.731 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 41","ack":true,"ts":1653323910722,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323910722} 2022-05-23 18:38:31.728 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":40,"ack":true,"ts":1653323911724,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323911724} 2022-05-23 18:38:31.733 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 40","ack":true,"ts":1653323911724,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323911724} 2022-05-23 18:38:32.728 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":39,"ack":true,"ts":1653323912724,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323912724} 2022-05-23 18:38:32.733 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 39","ack":true,"ts":1653323912724,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323912724} 2022-05-23 18:38:33.731 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":38,"ack":true,"ts":1653323913725,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323913725} 2022-05-23 18:38:33.737 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 38","ack":true,"ts":1653323913726,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323913726} 2022-05-23 18:38:34.142 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Node descriptor request for '20783' failed (Error: AREQ - ZDO - nodeDescRsp after 10000ms), retry 2022-05-23 18:38:34.143 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Discovering route to 20783 2022-05-23 18:38:34.143 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extRouteDisc - {"dstAddr":20783,"options":0,"radius":30} 2022-05-23 18:38:34.144 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,69,47,81,0,30,4] 2022-05-23 18:38:34.154 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,69,0,33] 2022-05-23 18:38:34.155 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,69,0,33] 2022-05-23 18:38:34.155 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 69 - [0] - 33 2022-05-23 18:38:34.156 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extRouteDisc - {"status":0} 2022-05-23 18:38:34.157 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:34.729 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":37,"ack":true,"ts":1653323914725,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323914725} 2022-05-23 18:38:34.734 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 37","ack":true,"ts":1653323914725,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323914725} 2022-05-23 18:38:35.784 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":36,"ack":true,"ts":1653323915771,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323915771} 2022-05-23 18:38:35.788 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 36","ack":true,"ts":1653323915780,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323915780} 2022-05-23 18:38:36.734 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":35,"ack":true,"ts":1653323916727,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323916727} 2022-05-23 18:38:36.741 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 35","ack":true,"ts":1653323916728,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323916728} 2022-05-23 18:38:37.157 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:38:37.158 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:38:37.169 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:38:37.170 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:38:37.171 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:38:37.171 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:38:37.172 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:37.738 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":34,"ack":true,"ts":1653323917731,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323917731} 2022-05-23 18:38:37.742 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 34","ack":true,"ts":1653323917734,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323917734} 2022-05-23 18:38:38.732 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":33,"ack":true,"ts":1653323918727,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323918727} 2022-05-23 18:38:38.737 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 33","ack":true,"ts":1653323918727,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323918727} 2022-05-23 18:38:39.732 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":32,"ack":true,"ts":1653323919728,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323919728} 2022-05-23 18:38:39.736 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 32","ack":true,"ts":1653323919728,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323919728} 2022-05-23 18:38:40.734 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":31,"ack":true,"ts":1653323920730,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323920730} 2022-05-23 18:38:40.740 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 31","ack":true,"ts":1653323920730,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323920730} 2022-05-23 18:38:41.735 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":30,"ack":true,"ts":1653323921730,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323921730} 2022-05-23 18:38:41.739 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 30","ack":true,"ts":1653323921731,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323921731} 2022-05-23 18:38:42.738 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":29,"ack":true,"ts":1653323922733,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323922733} 2022-05-23 18:38:42.745 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 29","ack":true,"ts":1653323922733,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323922733} 2022-05-23 18:38:43.736 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":28,"ack":true,"ts":1653323923731,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323923731} 2022-05-23 18:38:43.740 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 28","ack":true,"ts":1653323923732,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323923732} 2022-05-23 18:38:44.748 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":27,"ack":true,"ts":1653323924744,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323924744} 2022-05-23 18:38:44.752 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 27","ack":true,"ts":1653323924744,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323924744} 2022-05-23 18:38:45.742 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":26,"ack":true,"ts":1653323925735,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323925735} 2022-05-23 18:38:45.747 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 26","ack":true,"ts":1653323925735,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323925735} 2022-05-23 18:38:46.738 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":25,"ack":true,"ts":1653323926733,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323926733} 2022-05-23 18:38:46.742 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 25","ack":true,"ts":1653323926733,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323926733} 2022-05-23 18:38:47.173 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:38:47.174 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:38:47.174 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - node descriptor request failed for '0xb4e3f9fffe753122', attempt 1 2022-05-23 18:38:47.175 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:38:47.175 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:38:47.186 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:38:47.187 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:38:47.187 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:38:47.187 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:38:47.188 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:47.741 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":24,"ack":true,"ts":1653323927735,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323927735} 2022-05-23 18:38:47.747 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 24","ack":true,"ts":1653323927735,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323927735} 2022-05-23 18:38:48.740 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":23,"ack":true,"ts":1653323928735,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323928735} 2022-05-23 18:38:48.744 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 23","ack":true,"ts":1653323928735,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323928735} 2022-05-23 18:38:49.745 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":22,"ack":true,"ts":1653323929737,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323929737} 2022-05-23 18:38:49.751 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 22","ack":true,"ts":1653323929738,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323929738} 2022-05-23 18:38:52.887 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":21,"ack":true,"ts":1653323932734,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323932734} 2022-05-23 18:38:52.889 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 21","ack":true,"ts":1653323932734,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323932734} 2022-05-23 18:38:53.280 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":20,"ack":true,"ts":1653323932883,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323932883} 2022-05-23 18:38:53.283 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 20","ack":true,"ts":1653323932884,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323932884} 2022-05-23 18:38:53.384 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":19,"ack":true,"ts":1653323933286,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323933286} 2022-05-23 18:38:53.387 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 19","ack":true,"ts":1653323933287,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323933287} 2022-05-23 18:38:53.742 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":18,"ack":true,"ts":1653323933739,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323933739} 2022-05-23 18:38:53.745 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 18","ack":true,"ts":1653323933739,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323933739} 2022-05-23 18:38:55.912 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":17,"ack":true,"ts":1653323935874,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323935874} 2022-05-23 18:38:55.915 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 17","ack":true,"ts":1653323935875,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323935875} 2022-05-23 18:38:55.997 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":16,"ack":true,"ts":1653323935968,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323935968} 2022-05-23 18:38:56.004 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 16","ack":true,"ts":1653323935969,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323935969} 2022-05-23 18:38:56.749 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":15,"ack":true,"ts":1653323936743,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323936743} 2022-05-23 18:38:56.756 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 15","ack":true,"ts":1653323936743,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323936743} 2022-05-23 18:38:57.190 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Node descriptor request for '20783' failed (Error: AREQ - ZDO - nodeDescRsp after 10000ms), retry 2022-05-23 18:38:57.191 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Discovering route to 20783 2022-05-23 18:38:57.192 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extRouteDisc - {"dstAddr":20783,"options":0,"radius":30} 2022-05-23 18:38:57.193 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,69,47,81,0,30,4] 2022-05-23 18:38:57.206 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,69,0,33] 2022-05-23 18:38:57.207 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,69,0,33] 2022-05-23 18:38:57.208 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 69 - [0] - 33 2022-05-23 18:38:57.209 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extRouteDisc - {"status":0} 2022-05-23 18:38:57.210 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:38:57.748 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":14,"ack":true,"ts":1653323937743,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323937743} 2022-05-23 18:38:57.753 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 14","ack":true,"ts":1653323937743,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323937743} 2022-05-23 18:38:58.748 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":13,"ack":true,"ts":1653323938744,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323938744} 2022-05-23 18:38:58.753 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 13","ack":true,"ts":1653323938747,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323938747} 2022-05-23 18:38:59.750 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":12,"ack":true,"ts":1653323939746,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323939746} 2022-05-23 18:38:59.754 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 12","ack":true,"ts":1653323939746,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323939746} 2022-05-23 18:39:00.212 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:39:00.213 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:39:00.224 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:39:00.225 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:39:00.225 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:39:00.225 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:39:00.227 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:00.750 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":11,"ack":true,"ts":1653323940745,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323940745} 2022-05-23 18:39:00.753 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 11","ack":true,"ts":1653323940746,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323940746} 2022-05-23 18:39:01.751 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":10,"ack":true,"ts":1653323941746,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323941746} 2022-05-23 18:39:01.755 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 10","ack":true,"ts":1653323941747,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323941747} 2022-05-23 18:39:02.756 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":9,"ack":true,"ts":1653323942749,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323942749} 2022-05-23 18:39:02.764 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 9","ack":true,"ts":1653323942749,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323942749} 2022-05-23 18:39:03.751 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":8,"ack":true,"ts":1653323943748,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323943748} 2022-05-23 18:39:03.755 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 8","ack":true,"ts":1653323943748,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323943748} 2022-05-23 18:39:04.757 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":7,"ack":true,"ts":1653323944752,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323944752} 2022-05-23 18:39:04.765 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 7","ack":true,"ts":1653323944752,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323944752} 2022-05-23 18:39:05.269 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,123,128,0,121] 2022-05-23 18:39:05.270 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,123,128,0,121] 2022-05-23 18:39:05.271 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [123,128,0] - 121 2022-05-23 18:39:05.272 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":32891,"relaycount":0,"relaylist":[]} 2022-05-23 18:39:05.273 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:05.280 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,123,128,0,121] 2022-05-23 18:39:05.282 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,123,128,0,121] 2022-05-23 18:39:05.284 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [123,128,0] - 121 2022-05-23 18:39:05.286 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":32891,"relaycount":0,"relaylist":[]} 2022-05-23 18:39:05.287 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:05.341 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,47,81,34,49,117,254,255,249,227,180,123,128,207] 2022-05-23 18:39:05.342 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,47,81,34,49,117,254,255,249,227,180,123,128,207] 2022-05-23 18:39:05.342 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [47,81,34,49,117,254,255,249,227,180,123,128] - 207 2022-05-23 18:39:05.343 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":20783,"extaddr":"0xb4e3f9fffe753122","parentaddr":32891} 2022-05-23 18:39:05.344 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Device '0xb4e3f9fffe753122' joined 2022-05-23 18:39:05.345 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Not interviewing '0xb4e3f9fffe753122', completed 'false', in progress 'true' 2022-05-23 18:39:05.345 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:05.785 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":6,"ack":true,"ts":1653323945776,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323945776} 2022-05-23 18:39:05.793 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 6","ack":true,"ts":1653323945777,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323945777} 2022-05-23 18:39:06.761 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":5,"ack":true,"ts":1653323946752,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323946752} 2022-05-23 18:39:06.764 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 5","ack":true,"ts":1653323946753,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323946753} 2022-05-23 18:39:07.761 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":4,"ack":true,"ts":1653323947756,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323947756} 2022-05-23 18:39:07.764 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 4","ack":true,"ts":1653323947756,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323947756} 2022-05-23 18:39:08.764 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":3,"ack":true,"ts":1653323948757,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323948757} 2022-05-23 18:39:08.771 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 3","ack":true,"ts":1653323948758,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323948758} 2022-05-23 18:39:09.772 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":2,"ack":true,"ts":1653323949763,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323949763} 2022-05-23 18:39:09.779 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 2","ack":true,"ts":1653323949764,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323949764} 2022-05-23 18:39:10.228 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:39:10.229 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:39:10.229 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - node descriptor request failed for '0xb4e3f9fffe753122', attempt 2 2022-05-23 18:39:10.230 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:39:10.230 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:39:10.241 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:39:10.242 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:39:10.242 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:39:10.243 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:39:10.243 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:10.760 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":1,"ack":true,"ts":1653323950757,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323950757} 2022-05-23 18:39:10.764 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left: 1","ack":true,"ts":1653323950757,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323950757} 2022-05-23 18:39:11.756 - info: zigbee.1 (4854) Zigbee: stop joining 2022-05-23 18:39:11.757 - debug: zigbee.1 (4854) zigbee-herdsman:controller:log Disable joining 2022-05-23 18:39:11.759 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequestExt - {"dstaddrmode":2,"dstaddr":"0x000000000000fffd","destendpoint":242,"dstpanid":0,"srcendpoint":242,"clusterid":33,"transid":2,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[25,3,2,10,0,0]}} 2022-05-23 18:39:11.761 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,26,36,2,2,253,255,0,0,0,0,0,0,242,0,0,242,33,0,2,0,30,6,0,25,3,2,10,0,0,21] 2022-05-23 18:39:11.772 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,2,0,103] 2022-05-23 18:39:11.773 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,2,0,103] 2022-05-23 18:39:11.774 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 2 - [0] - 103 2022-05-23 18:39:11.775 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequestExt - {"status":0} 2022-05-23 18:39:11.776 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:11.781 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,242,2,55] 2022-05-23 18:39:11.782 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,242,2,55] 2022-05-23 18:39:11.783 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,242,2] - 55 2022-05-23 18:39:11.784 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":242,"transid":2} 2022-05-23 18:39:11.785 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:11.796 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingCountdown:{"val":0,"ack":true,"ts":1653323951787,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323951787} 2022-05-23 18:39:11.823 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMode:{"val":false,"ack":true,"ts":1653323951787,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653266443462} 2022-05-23 18:39:11.827 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Pairing time left","ack":true,"ts":1653323951788,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653323951788} 2022-05-23 18:39:11.977 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtPermitJoinReq - {"addrmode":15,"dstaddr":65532,"duration":0,"tcsignificance":0} 2022-05-23 18:39:11.978 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,54,15,252,255,0,0,26] 2022-05-23 18:39:11.991 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,54,0,82] 2022-05-23 18:39:11.991 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,54,0,82] 2022-05-23 18:39:11.992 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 54 - [0] - 82 2022-05-23 18:39:11.993 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtPermitJoinReq - {"status":0} 2022-05-23 18:39:11.993 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:11.998 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,69,203,0,143] 2022-05-23 18:39:11.999 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,69,203,0,143] 2022-05-23 18:39:12.000 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 2 - 5 - 203 - [0] - 143 2022-05-23 18:39:12.001 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - permitJoinInd - {"duration":0} 2022-05-23 18:39:12.001 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:12.005 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,182,0,0,0,240] 2022-05-23 18:39:12.006 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,182,0,0,0,240] 2022-05-23 18:39:12.006 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 182 - [0,0,0] - 240 2022-05-23 18:39:12.007 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtPermitJoinRsp - {"srcaddr":0,"status":0} 2022-05-23 18:39:12.008 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:20.244 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Node descriptor request for '20783' failed (Error: AREQ - ZDO - nodeDescRsp after 10000ms), retry 2022-05-23 18:39:20.245 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Discovering route to 20783 2022-05-23 18:39:20.245 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extRouteDisc - {"dstAddr":20783,"options":0,"radius":30} 2022-05-23 18:39:20.246 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,69,47,81,0,30,4] 2022-05-23 18:39:20.256 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,69,0,33] 2022-05-23 18:39:20.256 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,69,0,33] 2022-05-23 18:39:20.257 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 69 - [0] - 33 2022-05-23 18:39:20.257 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extRouteDisc - {"status":0} 2022-05-23 18:39:20.257 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:23.258 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:39:23.259 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:39:23.271 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:39:23.271 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:39:23.272 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:39:23.272 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:39:23.272 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:33.274 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:39:33.274 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:39:33.275 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - node descriptor request failed for '0xb4e3f9fffe753122', attempt 3 2022-05-23 18:39:33.275 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:39:33.277 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:39:33.288 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:39:33.291 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:39:33.292 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:39:33.293 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:39:33.294 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:43.297 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Node descriptor request for '20783' failed (Error: AREQ - ZDO - nodeDescRsp after 10000ms), retry 2022-05-23 18:39:43.297 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Discovering route to 20783 2022-05-23 18:39:43.298 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extRouteDisc - {"dstAddr":20783,"options":0,"radius":30} 2022-05-23 18:39:43.299 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,69,47,81,0,30,4] 2022-05-23 18:39:43.310 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,69,0,33] 2022-05-23 18:39:43.310 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,69,0,33] 2022-05-23 18:39:43.312 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 69 - [0] - 33 2022-05-23 18:39:43.313 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extRouteDisc - {"status":0} 2022-05-23 18:39:43.313 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:46.316 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:39:46.317 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:39:46.329 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:39:46.330 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:39:46.330 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:39:46.331 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:39:46.332 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:39:56.333 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:39:56.334 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:39:56.336 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - node descriptor request failed for '0xb4e3f9fffe753122', attempt 4 2022-05-23 18:39:56.336 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:39:56.337 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:39:56.349 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:39:56.350 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:39:56.351 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:39:56.351 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:39:56.352 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:06.353 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Node descriptor request for '20783' failed (Error: AREQ - ZDO - nodeDescRsp after 10000ms), retry 2022-05-23 18:40:06.354 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Discovering route to 20783 2022-05-23 18:40:06.354 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extRouteDisc - {"dstAddr":20783,"options":0,"radius":30} 2022-05-23 18:40:06.355 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,69,47,81,0,30,4] 2022-05-23 18:40:06.365 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,69,0,33] 2022-05-23 18:40:06.365 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,69,0,33] 2022-05-23 18:40:06.365 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 69 - [0] - 33 2022-05-23 18:40:06.366 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extRouteDisc - {"status":0} 2022-05-23 18:40:06.366 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:09.367 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:40:09.368 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:40:09.380 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:40:09.381 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:40:09.382 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:40:09.383 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:40:09.383 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:19.385 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:40:19.386 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:40:19.387 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - node descriptor request failed for '0xb4e3f9fffe753122', attempt 5 2022-05-23 18:40:19.387 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:40:19.388 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:40:19.399 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:40:19.400 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:40:19.400 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:40:19.401 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:40:19.402 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:29.404 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Node descriptor request for '20783' failed (Error: AREQ - ZDO - nodeDescRsp after 10000ms), retry 2022-05-23 18:40:29.404 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:adapter Discovering route to 20783 2022-05-23 18:40:29.405 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extRouteDisc - {"dstAddr":20783,"options":0,"radius":30} 2022-05-23 18:40:29.405 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,69,47,81,0,30,4] 2022-05-23 18:40:29.415 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,69,0,33] 2022-05-23 18:40:29.416 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,69,0,33] 2022-05-23 18:40:29.416 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 69 - [0] - 33 2022-05-23 18:40:29.417 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extRouteDisc - {"status":0} 2022-05-23 18:40:29.418 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:32.419 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":20783,"nwkaddrofinterest":20783} 2022-05-23 18:40:32.421 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,47,81,47,81,35] 2022-05-23 18:40:32.432 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] 2022-05-23 18:40:32.433 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] 2022-05-23 18:40:32.434 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102 2022-05-23 18:40:32.434 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} 2022-05-23 18:40:32.435 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:40.726 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,123,128,0,121] 2022-05-23 18:40:40.727 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,123,128,0,121] 2022-05-23 18:40:40.727 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [123,128,0] - 121 2022-05-23 18:40:40.727 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":32891,"relaycount":0,"relaylist":[]} 2022-05-23 18:40:40.728 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:40.786 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,28,69,184,123,128,0,0,248,255,7,23,0,6,0,16,161,165,161,158,206,162,177,164,163,165,206,208,204,203,180,192,50] 2022-05-23 18:40:40.786 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,28,69,184,123,128,0,0,248,255,7,23,0,6,0,16,161,165,161,158,206,162,177,164,163,165,206,208,204,203,180,192,50] 2022-05-23 18:40:40.787 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 28 - 2 - 5 - 184 - [123,128,0,0,248,255,7,23,0,6,0,16,161,165,161,158,206,162,177,164,163,165,206,208,204,203,180,192] - 50 2022-05-23 18:40:40.787 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtNwkUpdateNotify - {"srcaddr":32891,"status":0,"scannedchannels":134215680,"totaltrans":23,"transfails":6,"energylength":16,"energyvalues":[161,165,161,158,206,162,177,164,163,165,206,208,204,203,180,192]} 2022-05-23 18:40:40.788 - debug: zigbee.1 (4854) zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] 2022-05-23 18:40:42.436 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:40:42.437 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:40:42.438 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - node descriptor request failed for '0xb4e3f9fffe753122', attempt 6 2022-05-23 18:40:42.439 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks check for 'undefined'-'undefined'-'Unknown' 2022-05-23 18:40:42.440 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - quirks did not match 2022-05-23 18:40:42.442 - debug: zigbee.1 (4854) zigbee-herdsman:controller:device:log Interview - failed for device '0xb4e3f9fffe753122' with error 'Error: Interview failed because can not get node descriptor ('0xb4e3f9fffe753122') at Device.interviewInternal (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:454:19)' 2022-05-23 18:40:42.444 - debug: zigbee.1 (4854) zigbee-herdsman:controller:error Interview failed for '0xb4e3f9fffe753122 with error 'Error: Interview failed because can not get node descriptor ('0xb4e3f9fffe753122')' 2022-05-23 18:40:42.446 - debug: zigbee.1 (4854) handleDeviceInterview. {"status":"failed","device":{"_events":{},"_eventsCount":0,"_maxListeners":100,"ID":2,"_type":"Unknown","_ieeeAddr":"0xb4e3f9fffe753122","_networkAddress":20783,"_endpoints":[],"_interviewCompleted":false,"_interviewing":false,"_skipDefaultResponse":false,"_skipTimeResponse":false,"meta":{},"_lastSeen":1653323945344,"_defaultSendRequestWhen":"immediate"}} 2022-05-23 18:40:42.447 - error: zigbee.1 (4854) Failed to interview '0xb4e3f9fffe753122', device has not succesfully been paired. undefined 2022-05-23 18:40:42.477 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Error: Failed to interview '0xb4e3f9fffe753122', device has not succesfully been paired. undefined. undefined","ack":true,"ts":1653324042471,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653324042471} 2022-05-23 18:40:42.482 - silly: zigbee.1 (4854) States user redis pmessage zigbee.1.*/zigbee.1.info.pairingMessage:{"val":"Interview failed: 0xb4e3f9fffe753122","ack":true,"ts":1653324042472,"q":0,"from":"system.adapter.zigbee.1","user":"system.user.admin","lc":1653324042472}