2021-08-21 10:24:52.860 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:ZigBee_Bridge:24fd5b00010b859e' changed from ONLINE to UNINITIALIZED 2021-08-21 10:24:52.888 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:ZigBee_Bridge:24fd5b00010b859e' changed from UNINITIALIZED to UNINITIALIZED (DISABLED) 2021-08-21 10:24:57.170 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing ZigBee thing handler zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:24:57.171 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Coordinator status changed to ONLINE. 2021-08-21 10:24:57.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Coordinator is ONLINE. Starting device initialisation. 2021-08-21 10:24:57.173 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 24FD5B00010B859E: NWK Discovery starting node rediscovery 2021-08-21 10:24:57.174 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 24FD5B00010B859E: NWK Discovery: Rediscovery using unicast to CD16 2021-08-21 10:24:57.175 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> CD16/0, cluster=0000, TID=--, ieeeAddr=24FD5B00010B859E, requestType=0, startIndex=0]] 2021-08-21 10:24:57.179 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> CD16/0, cluster=0000, TID=6A, ieeeAddr=24FD5B00010B859E, requestType=0, startIndex=0]] 2021-08-21 10:24:57.180 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> CD16/0, cluster=0000, TID=6A, ieeeAddr=24FD5B00010B859E, requestType=0, startIndex=0] 2021-08-21 10:24:57.184 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Start initialising ZigBee Thing handler 2021-08-21 10:24:57.185 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: ZigBee node property discovery start 2021-08-21 10:24:57.185 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: ZigBee node property discovery using basic cluster on endpoint CD16/1 2021-08-21 10:24:57.186 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=--, identifiers=[3, 2, 6]]] 2021-08-21 10:24:57.231 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [0000/0 -> 0000/0, cluster=8000, TID=6A, status=SUCCESS, ieeeAddrRemoteDev=24FD5B00010B859E, nwkAddrRemoteDev=CD16, startIndex=null, nwkAddrAssocDevList=[]] 2021-08-21 10:24:57.232 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [0000/0 -> 0000/0, cluster=8000, TID=6A, status=SUCCESS, ieeeAddrRemoteDev=24FD5B00010B859E, nwkAddrRemoteDev=CD16, startIndex=null, nwkAddrAssocDevList=[]] 2021-08-21 10:24:57.233 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=57, state=COMPLETE, sendCnt=1, command=NetworkAddressRequest [0000/0 -> CD16/0, cluster=0000, TID=6A, ieeeAddr=24FD5B00010B859E, requestType=0, startIndex=0]] 2021-08-21 10:24:57.233 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 24FD5B00010B859E: NWK Discovery NetworkAddressRequest returned from NetworkAddressResponse [0000/0 -> 0000/0, cluster=8000, TID=6A, status=SUCCESS, ieeeAddrRemoteDev=24FD5B00010B859E, nwkAddrRemoteDev=CD16, startIndex=null, nwkAddrAssocDevList=[]] 2021-08-21 10:24:57.234 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 24FD5B00010B859E: NWK Discovery: Rediscovery found network address to CD16 2021-08-21 10:24:57.235 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 24FD5B00010B859E: NWK Discovery add node CD16 2021-08-21 10:24:57.235 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:24:57.236 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Updating node NWK=CD16 2021-08-21 10:24:57.237 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=51, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6B, identifiers=[3, 2, 6]]] 2021-08-21 10:24:57.237 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:24:57.239 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:24:57.164 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:ZigBee_Bridge:24fd5b00010b859e' changed from UNINITIALIZED (DISABLED) to INITIALIZING 2021-08-21 10:24:57.171 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:ZigBee_Bridge:24fd5b00010b859e' changed from INITIALIZING to UNKNOWN 2021-08-21 10:24:57.175 [INFO ] [penhab.event.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:ZigBee_Bridge:24fd5b00010b859e changed to UNKNOWN. 2021-08-21 10:25:00.618 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:00.619 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:00.625 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3436, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6B, identifiers=[3, 2, 6]]] 2021-08-21 10:25:00.627 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=--, identifiers=[3]]] 2021-08-21 10:25:00.628 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:00.630 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6C, identifiers=[3]]] 2021-08-21 10:25:00.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:00.886 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:00.890 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: Hardware version failed 2021-08-21 10:25:00.890 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=264, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6C, identifiers=[3]]] 2021-08-21 10:25:00.893 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=--, identifiers=[2]]] 2021-08-21 10:25:00.894 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:00.896 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6D, identifiers=[2]]] 2021-08-21 10:25:01.152 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:01.152 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:01.156 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: Stack version failed 2021-08-21 10:25:01.156 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=263, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6D, identifiers=[2]]] 2021-08-21 10:25:01.157 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:01.159 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=--, identifiers=[6]]] 2021-08-21 10:25:01.161 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6E, identifiers=[6]]] 2021-08-21 10:25:01.419 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:01.421 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:01.427 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: Date code failed 2021-08-21 10:25:01.427 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=267, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Basic: 0000/0 -> CD16/1, cluster=0000, TID=6E, identifiers=[6]]] 2021-08-21 10:25:01.428 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: ZigBee node property discovery using OTA cluster on endpoint CD16/1 2021-08-21 10:25:01.429 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B00010B859E: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x110a, modelId=multiv4, zigbee_networkaddress=52502, zigbee_powersource=MAINS, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=SmartThings, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], firmwareVersion=0x0000001B, zigbee_neighbors=[], zigbee_applicationVersion=27, zigbee_device_initialised=true, zigbee_devices=[]} 2021-08-21 10:25:01.429 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:01.430 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Checking endpoint 1 channels 2021-08-21 10:25:01.433 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=--, identifiers=[33]]] 2021-08-21 10:25:01.434 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=2, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=6F, identifiers=[33]]] 2021-08-21 10:25:01.689 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:01.690 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:01.695 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=263, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=6F, identifiers=[33]]] 2021-08-21 10:25:01.696 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.697 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:01.700 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.701 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.703 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.707 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.708 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.711 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.712 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.713 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: IAS zone type STANDARD_CIE 2021-08-21 10:25:01.715 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Dynamically created 7 channels 2021-08-21 10:25:01.716 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Device initialization will be skipped as the device is already initialized 2021-08-21 10:25:01.716 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: Initialising device IAS Zone cluster for zigbee:ias_standard_system 2021-08-21 10:25:01.718 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=--, identifiers=[2]]] 2021-08-21 10:25:01.719 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=70, identifiers=[2]]] 2021-08-21 10:25:01.957 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:01.958 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:01.964 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=246, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=70, identifiers=[2]]] 2021-08-21 10:25:01.964 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=IAS Zone, id=2, name=Zone Status, dataType=BITMAP_16_BIT, lastValue=33, lastReportTime=Sat Aug 21 10:25:01 PDT 2021, implemented=false] 2021-08-21 10:25:01.965 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Temperature Measurement: 0000/0 -> CD16/1, cluster=0402, TID=--, identifiers=[0]]] 2021-08-21 10:25:01.966 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system updated to ON 2021-08-21 10:25:01.967 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system to ON 2021-08-21 10:25:01.967 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:01.970 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Temperature Measurement: 0000/0 -> CD16/1, cluster=0402, TID=71, identifiers=[0]]] 2021-08-21 10:25:02.222 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:02.223 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:02.228 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=263, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Temperature Measurement: 0000/0 -> CD16/1, cluster=0402, TID=71, identifiers=[0]]] 2021-08-21 10:25:02.228 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: Initialising device IAS Zone cluster for zigbee:ias_tamper 2021-08-21 10:25:02.229 [DEBUG] [converter.ZigBeeConverterTemperature] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=Temperature Measurement, id=0, name=Measured Value, dataType=SIGNED_16_BIT_INTEGER, lastValue=2428, lastReportTime=Sat Aug 21 10:25:02 PDT 2021, implemented=false] 2021-08-21 10:25:02.230 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:02.230 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_temperature updated to 24.28 °C 2021-08-21 10:25:02.231 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_temperature to 24.28 °C 2021-08-21 10:25:02.232 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=--, identifiers=[2]]] 2021-08-21 10:25:02.234 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=2, state=WAITING, sendCnt=0, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=72, identifiers=[2]]] 2021-08-21 10:25:02.492 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:02.493 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:02.497 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=265, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=72, identifiers=[2]]] 2021-08-21 10:25:02.499 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=IAS Zone, id=2, name=Zone Status, dataType=BITMAP_16_BIT, lastValue=33, lastReportTime=Sat Aug 21 10:25:02 PDT 2021, implemented=false] 2021-08-21 10:25:02.499 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=IAS Zone, id=2, name=Zone Status, dataType=BITMAP_16_BIT, lastValue=33, lastReportTime=Sat Aug 21 10:25:02 PDT 2021, implemented=false] 2021-08-21 10:25:02.500 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:02.500 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_tamper updated to OFF 2021-08-21 10:25:02.501 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system updated to ON 2021-08-21 10:25:02.501 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_tamper to OFF 2021-08-21 10:25:02.502 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system to ON 2021-08-21 10:25:02.502 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=--, identifiers=[62]]] 2021-08-21 10:25:02.503 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=73, identifiers=[62]]] 2021-08-21 10:25:02.754 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:02.754 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:02.758 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=256, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=73, identifiers=[62]]] 2021-08-21 10:25:02.759 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: Initialising device IAS Zone cluster for system:low-battery 2021-08-21 10:25:02.760 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:02.762 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=--, identifiers=[2]]] 2021-08-21 10:25:02.764 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=2, state=WAITING, sendCnt=0, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=74, identifiers=[2]]] 2021-08-21 10:25:03.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:03.028 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:03.033 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=270, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=74, identifiers=[2]]] 2021-08-21 10:25:03.033 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=IAS Zone, id=2, name=Zone Status, dataType=BITMAP_16_BIT, lastValue=33, lastReportTime=Sat Aug 21 10:25:03 PDT 2021, implemented=false] 2021-08-21 10:25:03.034 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system updated to ON 2021-08-21 10:25:03.034 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=--, identifiers=[32]]] 2021-08-21 10:25:03.035 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system to ON 2021-08-21 10:25:03.035 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=IAS Zone, id=2, name=Zone Status, dataType=BITMAP_16_BIT, lastValue=33, lastReportTime=Sat Aug 21 10:25:03 PDT 2021, implemented=false] 2021-08-21 10:25:03.036 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_tamper updated to OFF 2021-08-21 10:25:03.036 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:03.037 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=IAS Zone, id=2, name=Zone Status, dataType=BITMAP_16_BIT, lastValue=33, lastReportTime=Sat Aug 21 10:25:03 PDT 2021, implemented=false] 2021-08-21 10:25:03.037 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_tamper to OFF 2021-08-21 10:25:03.038 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_iaslowbattery updated to OFF 2021-08-21 10:25:03.038 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=75, identifiers=[32]]] 2021-08-21 10:25:03.038 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_iaslowbattery to OFF 2021-08-21 10:25:03.295 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:03.296 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:03.302 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=268, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=75, identifiers=[32]]] 2021-08-21 10:25:03.303 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Binary Input (Basic): 0000/0 -> CD16/1, cluster=000F, TID=--, identifiers=[85]]] 2021-08-21 10:25:03.304 [DEBUG] [verter.ZigBeeConverterBatteryVoltage] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=Power Configuration, id=32, name=Battery Voltage, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=27, lastReportTime=Sat Aug 21 10:25:03 PDT 2021, implemented=true] 2021-08-21 10:25:03.305 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:03.306 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_batteryvoltage updated to 2.7 V 2021-08-21 10:25:03.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_batteryvoltage to 2.7 V 2021-08-21 10:25:03.307 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Binary Input (Basic): 0000/0 -> CD16/1, cluster=000F, TID=76, identifiers=[85]]] 2021-08-21 10:25:03.562 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:03.563 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:03.567 [DEBUG] [converter.ZigBeeConverterBinaryInput] - 24FD5B00010B859E: ZigBee attribute reports ZclAttribute [cluster=Binary Input (Basic), id=85, name=Present Value, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Aug 21 10:25:03 PDT 2021, implemented=false] 2021-08-21 10:25:03.567 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Channel initialisation complete 2021-08-21 10:25:03.568 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=264, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Binary Input (Basic): 0000/0 -> CD16/1, cluster=000F, TID=76, identifiers=[85]]] 2021-08-21 10:25:03.568 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B00010B859E: Channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_binaryinput updated to OFF 2021-08-21 10:25:03.569 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Thing is RFD, using long poll period of 1800sec 2021-08-21 10:25:03.569 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_binaryinput to OFF 2021-08-21 10:25:03.570 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Setting ONLINE/OFFLINE timeout interval to: 14430 2021-08-21 10:25:03.570 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:03.571 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:03.571 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:03.571 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:03.574 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> CD16/0, cluster=0033, TID=--, startIndex=0]] 2021-08-21 10:25:03.575 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=2, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> CD16/0, cluster=0033, TID=77, startIndex=0]] 2021-08-21 10:25:03.850 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:03.851 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:03.854 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementBindResponse [CD16/0 -> 0000/0, cluster=8033, TID=77, status=SUCCESS, bindingTableEntries=4, startIndex=0, bindingTableList=[BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0500], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0402], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0001]]] 2021-08-21 10:25:03.857 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementBindResponse [CD16/0 -> 0000/0, cluster=8033, TID=77, status=SUCCESS, bindingTableEntries=4, startIndex=0, bindingTableList=[BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0500], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0402], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0001]]] 2021-08-21 10:25:03.858 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=285, state=COMPLETE, sendCnt=1, command=ManagementBindRequest [0000/0 -> CD16/0, cluster=0033, TID=77, startIndex=0]] 2021-08-21 10:25:03.858 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> CD16/0, cluster=0033, TID=--, startIndex=3]] 2021-08-21 10:25:03.860 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:03.861 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> CD16/0, cluster=0033, TID=78, startIndex=3]] 2021-08-21 10:25:04.098 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:04.099 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:04.101 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementBindResponse [CD16/0 -> 0000/0, cluster=8033, TID=78, status=SUCCESS, bindingTableEntries=4, startIndex=3, bindingTableList=[BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=000F]]] 2021-08-21 10:25:04.102 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementBindResponse [CD16/0 -> 0000/0, cluster=8033, TID=78, status=SUCCESS, bindingTableEntries=4, startIndex=3, bindingTableList=[BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=000F]]] 2021-08-21 10:25:04.103 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=245, state=COMPLETE, sendCnt=1, command=ManagementBindRequest [0000/0 -> CD16/0, cluster=0033, TID=78, startIndex=3]] 2021-08-21 10:25:04.105 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:04.105 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 24FD5B00010B859E: Binding table updated: [BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0500], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0402], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=0001], BindingTable [srcAddr=24FD5B00010B859E/1, dstAddr=000D6F000B3D30F5/1, clusterId=000F]] 2021-08-21 10:25:04.107 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Polling initialised at 1807509ms 2021-08-21 10:25:04.108 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Done initialising ZigBee Thing handler 2021-08-21 10:25:04.110 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 24FD5B00010B859E: Data store: Deferring write for 250ms. 2021-08-21 10:25:04.112 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.112 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.113 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.113 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.114 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.115 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.119 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.120 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.120 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.121 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.122 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.122 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.123 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.123 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.124 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.124 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.125 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.126 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.127 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.128 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.128 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.129 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.130 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.131 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.132 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.133 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e 2021-08-21 10:25:04.133 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b00010b859e in 14430 seconds 2021-08-21 10:25:04.361 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 24FD5B00010B859E: Data store: Writing node. 2021-08-21 10:25:04.410 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 24FD5B00010B859E: ZigBee saving network state complete. 2021-08-21 10:25:04.108 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:ZigBee_Bridge:24fd5b00010b859e' changed from UNKNOWN to ONLINE 2021-08-21 10:25:04.661 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Configuration received: {zigbee_macaddress=24FD5B00010B859E, zigbee_initialise_device=true} 2021-08-21 10:25:04.663 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Configuration update: Ignored zigbee_macaddress as no change 2021-08-21 10:25:04.670 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Configuration updated: Reinitialise device 2021-08-21 10:25:04.671 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing device 2021-08-21 10:25:04.672 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_system with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasCieSystem@c61dd7 2021-08-21 10:25:04.673 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: Initialising device IAS Zone cluster for zigbee:ias_standard_system 2021-08-21 10:25:04.674 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:04.675 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=79, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:04.677 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=79, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:11.608 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:11.609 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 24FD5B00010B859E: Binding table updated 2021-08-21 10:25:11.610 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2] 2021-08-21 10:25:11.610 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 24FD5B00010B859E: Data store: Deferring write for 250ms. 2021-08-21 10:25:11.611 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Node updated - ZigBeeNode [state=ONLINE, IEEE=24FD5B00010B859E, NWK=CD16, Type=END_DEVICE, endpoints=[1]] 2021-08-21 10:25:11.616 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=6941, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=79, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:11.616 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:11.617 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:11.619 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=7A, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:11.861 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 24FD5B00010B859E: Data store: Writing node. 2021-08-21 10:25:11.882 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:11.883 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:11.887 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=7A, status=SUCCESS, records=null] 2021-08-21 10:25:11.887 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=7A, status=SUCCESS, records=null] 2021-08-21 10:25:11.888 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_temperature with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterTemperature@1bc52f9 2021-08-21 10:25:11.888 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=7A, status=SUCCESS, records=null] 2021-08-21 10:25:11.888 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=272, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=7A, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:11.889 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:11.891 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:11.893 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7B, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:11.895 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7B, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:11.909 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 24FD5B00010B859E: ZigBee saving network state complete. 2021-08-21 10:25:12.147 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:12.148 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:12.152 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=262, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7B, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:12.152 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Temperature Measurement: 0000/0 -> CD16/1, cluster=0402, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]] 2021-08-21 10:25:12.153 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:12.155 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Temperature Measurement: 0000/0 -> CD16/1, cluster=0402, TID=7C, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]] 2021-08-21 10:25:12.417 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:12.419 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:12.427 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_tamper with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasTamper@1568b12 2021-08-21 10:25:12.428 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: Initialising device IAS Zone cluster for zigbee:ias_tamper 2021-08-21 10:25:12.428 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=277, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Temperature Measurement: 0000/0 -> CD16/1, cluster=0402, TID=7C, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]] 2021-08-21 10:25:12.430 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:12.431 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:12.433 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7D, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:12.435 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7D, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:12.687 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:12.688 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:12.692 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=262, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7D, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:12.692 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:12.694 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:12.696 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=7E, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:12.961 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:12.962 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:12.967 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=274, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=7E, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:12.967 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_batteryalarm with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryAlarm@1f2094a 2021-08-21 10:25:12.968 [DEBUG] [onverter.ZigBeeConverterBatteryAlarm] - 24FD5B00010B859E: Initialising device battery alarm converter 2021-08-21 10:25:12.969 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:12.970 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=7E, status=SUCCESS, records=null] 2021-08-21 10:25:12.970 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=7E, status=SUCCESS, records=null] 2021-08-21 10:25:12.970 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=7E, status=SUCCESS, records=null] 2021-08-21 10:25:12.973 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:12.975 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=1, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7F, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:12.976 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7F, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:13.229 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:13.230 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:13.233 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=260, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=7F, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:13.233 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_32_BIT, attributeIdentifier=62, direction=0, minimumReportingInterval=600, maximumReportingInterval=7200]]]] 2021-08-21 10:25:13.236 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:13.237 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=80, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_32_BIT, attributeIdentifier=62, direction=0, minimumReportingInterval=600, maximumReportingInterval=7200]]]] 2021-08-21 10:25:13.504 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:13.505 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:13.508 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=275, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=80, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_32_BIT, attributeIdentifier=62, direction=0, minimumReportingInterval=600, maximumReportingInterval=7200]]]] 2021-08-21 10:25:13.509 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_iaslowbattery with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasLowBattery@ad4ea3 2021-08-21 10:25:13.510 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:13.511 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: Initialising device IAS Zone cluster for system:low-battery 2021-08-21 10:25:13.513 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:13.515 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=81, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:13.518 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=81, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:13.768 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:13.769 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:13.773 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=261, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=81, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1280, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:13.774 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:13.775 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:13.777 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=82, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:14.042 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:14.043 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:14.047 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=273, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [IAS Zone: 0000/0 -> CD16/1, cluster=0500, TID=82, records=[AttributeReportingConfigurationRecord [attributeDataType=BITMAP_16_BIT, attributeIdentifier=2, direction=0, minimumReportingInterval=3, maximumReportingInterval=7200]]]] 2021-08-21 10:25:14.047 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=82, status=SUCCESS, records=null] 2021-08-21 10:25:14.047 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_batteryvoltage with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryVoltage@b3a301 2021-08-21 10:25:14.048 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=82, status=SUCCESS, records=null] 2021-08-21 10:25:14.048 [DEBUG] [verter.ZigBeeConverterBatteryVoltage] - 24FD5B00010B859E: Initialising device battery voltage converter 2021-08-21 10:25:14.048 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B00010B859E: ZigBee command report ConfigureReportingResponse [IAS Zone: CD16/1 -> 0000/1, cluster=0500, TID=82, status=SUCCESS, records=null] 2021-08-21 10:25:14.049 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:14.051 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:14.053 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=2, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=83, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:14.055 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=83, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:14.311 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:14.312 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:14.315 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=264, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=83, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:14.315 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=32, direction=0, minimumReportingInterval=600, maximumReportingInterval=7200, reportableChange=1]]]] 2021-08-21 10:25:14.318 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:14.320 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=4, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=84, records=[AttributeReportingConfigurationRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=32, direction=0, minimumReportingInterval=600, maximumReportingInterval=7200, reportableChange=1]]]] 2021-08-21 10:25:14.586 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:14.587 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:14.591 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=275, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Power Configuration: 0000/0 -> CD16/1, cluster=0001, TID=84, records=[AttributeReportingConfigurationRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=32, direction=0, minimumReportingInterval=600, maximumReportingInterval=7200, reportableChange=1]]]] 2021-08-21 10:25:14.591 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B00010B859E: Initializing channel zigbee:device:ZigBee_Bridge:24fd5b00010b859e:24FD5B00010B859E_1_binaryinput with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBinaryInput@a1cb6f 2021-08-21 10:25:14.592 [DEBUG] [converter.ZigBeeConverterBinaryInput] - 24FD5B00010B859E: Initialising device binary input cluster 2021-08-21 10:25:14.593 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:14.595 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=--, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=15, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:14.597 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/0: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=85, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=15, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:14.602 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CD16/0, cluster=0021, TID=85, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=15, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1] 2021-08-21 10:25:14.854 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:14.855 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:14.858 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=264, state=COMPLETE, sendCnt=1, command=BindRequest [0000/0 -> CD16/0, cluster=0021, TID=85, srcAddress=24FD5B00010B859E, srcEndpoint=1, bindCluster=15, dstAddrMode=3, dstAddress=000D6F000B3D30F5, dstEndpoint=1]] 2021-08-21 10:25:14.859 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Binary Input (Basic): 0000/0 -> CD16/1, cluster=000F, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=BOOLEAN, attributeIdentifier=85, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200]]]] 2021-08-21 10:25:14.860 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0 2021-08-21 10:25:14.862 [DEBUG] [transaction.ZigBeeTransactionManager] - CD16/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=3, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Binary Input (Basic): 0000/0 -> CD16/1, cluster=000F, TID=86, records=[AttributeReportingConfigurationRecord [attributeDataType=BOOLEAN, attributeIdentifier=85, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200]]]] 2021-08-21 10:25:15.126 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node update. NWK Address=CD16 2021-08-21 10:25:15.128 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 24FD5B00010B859E: Node CD16 is not updated 2021-08-21 10:25:15.133 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B00010B859E queueTime=274, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Binary Input (Basic): 0000/0 -> CD16/1, cluster=000F, TID=86, records=[AttributeReportingConfigurationRecord [attributeDataType=BOOLEAN, attributeIdentifier=85, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200]]]] 2021-08-21 10:25:15.136 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B00010B859E: transactionComplete, state=COMPLETE, outstanding=0