2021-01-07 10:37:41.098 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Start initialising ZigBee Thing handler

2021-01-07 10:37:41.109 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 00158D00063312E4: NWK Discovery: Rediscovery using unicast to AEA4

2021-01-07 10:37:41.111 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00063312E4: ZigBee node property discovery start

2021-01-07 10:37:41.121 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00063312E4: ZigBee node property discovery using basic cluster on endpoint AEA4/1

2021-01-07 10:37:41.131 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00063312E4: ZigBee node property discovery using OTA cluster on endpoint AEA4/1

2021-01-07 10:37:41.136 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2

2021-01-07 10:37:41.139 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D00063312E4: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=20, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> AEA4/0, cluster=0000, TID=--, ieeeAddr=00158D00063312E4, requestType=0, startIndex=0]]

2021-01-07 10:37:41.146 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3

2021-01-07 10:37:41.155 [DEBUG] [transaction.ZigBeeTransactionManager] - AEA4/0: Sending ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=38, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> AEA4/0, cluster=0000, TID=78, ieeeAddr=00158D00063312E4, requestType=0, startIndex=0]]

2021-01-07 10:37:41.159 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding

2021-01-07 10:37:41.167 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> AEA4/0, cluster=0000, TID=78, ieeeAddr=00158D00063312E4, requestType=0, startIndex=0]

2021-01-07 10:37:41.179 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=AEA4/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=B4, rssi=--, lqi=--, payload=78 E4 12 33 06 00 8D 15 00 00 00]

2021-01-07 10:37:41.184 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 A4 AE 00 00 00 00 B4 30 08 0B 78 E4 12 33 06 00 8D 15 00 00 00 9E, checksum=9E, error=false) 

2021-01-07 10:37:41.196 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D00063312E4: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0000/0 -> AEA4/1, cluster=0019, TID=--, identifiers=[2]]]

2021-01-07 10:37:41.209 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=1/3

2021-01-07 10:37:41.214 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)

2021-01-07 10:37:41.220 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=78 state=TX_ACK

2021-01-07 10:37:41.225 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=78, state=TX_ACK, outstanding=1

2021-01-07 10:37:41.232 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=AEA4, TID=78, event=TX_ACK, state=TRANSMITTED

==> /var/log/openhab/events.log <==

2021-01-07 10:37:41.108 [INFO ] [penhab.event.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:usb_stick:00158d00063312e4 changed to UNKNOWN.

==> /var/log/openhab/openhab.log <==

2021-01-07 10:37:46.224 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 E4 12 33 06 00 8D 15 00 A4 AE 00 00 99)

2021-01-07 10:37:46.228 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 E4 12 33 06 00 8D 15 00 A4 AE 00 00 99, checksum=99, error=false

2021-01-07 10:37:46.231 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580

2021-01-07 10:37:46.245 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF A4 AE 00 00 80 00 78 00 00 00 E4 12 33 06 00 8D 15 00 A4 AE 0D)

2021-01-07 10:37:46.248 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF A4 AE 00 00 80 00 78 00 00 00 E4 12 33 06 00 8D 15 00 A4 AE 0D, checksum=0D, error=false

2021-01-07 10:37:46.254 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 E4 12 33 06 00 8D 15 00 A4 AE]

2021-01-07 10:37:46.261 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [AEA4/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=00158D00063312E4, nwkAddrRemoteDev=AEA4, startIndex=null, nwkAddrAssocDevList=[]]

2021-01-07 10:37:46.267 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [AEA4/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=00158D00063312E4, nwkAddrRemoteDev=AEA4, startIndex=null, nwkAddrAssocDevList=[]] 

2021-01-07 10:37:46.273 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 A4 AE E4 12 33 06 00 8D 15 00 98)

2021-01-07 10:37:46.276 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 A4 AE E4 12 33 06 00 8D 15 00 98, checksum=98, error=false

2021-01-07 10:37:46.276 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction response received - waiting TX_ACK: ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=5156, state=RESPONDED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> AEA4/0, cluster=0000, TID=78, ieeeAddr=00158D00063312E4, requestType=0, startIndex=0]]

2021-01-07 10:37:46.279 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685

2021-01-07 10:37:49.235 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 00158D00063312E4: NWK Discovery NetworkAddressRequest returned from NetworkAddressResponse [AEA4/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=00158D00063312E4, nwkAddrRemoteDev=AEA4, startIndex=null, nwkAddrAssocDevList=[]]

2021-01-07 10:37:49.235 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=8118, state=COMPLETE, sendCnt=1, command=NetworkAddressRequest [0000/0 -> AEA4/0, cluster=0000, TID=78, ieeeAddr=00158D00063312E4, requestType=0, startIndex=0]]

2021-01-07 10:37:49.239 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding

2021-01-07 10:37:49.241 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 00158D00063312E4: NWK Discovery: Rediscovery found network address to AEA4

2021-01-07 10:37:49.242 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D00063312E4: transactionComplete, state=COMPLETE, outstanding=0

2021-01-07 10:37:49.246 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3

2021-01-07 10:37:49.246 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 00158D00063312E4: NWK Discovery add node AEA4

2021-01-07 10:37:49.250 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00158D00063312E4: Node state updated from UNKNOWN to ONLINE

2021-01-07 10:37:49.252 [DEBUG] [transaction.ZigBeeTransactionManager] - AEA4/1: Sending ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=8059, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0000/0 -> AEA4/1, cluster=0019, TID=79, identifiers=[2]]]

2021-01-07 10:37:49.255 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding

2021-01-07 10:37:49.256 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00063312E4: Updating node NWK=AEA4

2021-01-07 10:37:49.260 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Ota Upgrade: 0000/0 -> AEA4/1, cluster=0019, TID=79, identifiers=[2]]

2021-01-07 10:37:49.261 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00063312E4: Node update. NWK Address=AEA4

2021-01-07 10:37:49.264 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=121, commandId=0]

2021-01-07 10:37:49.266 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00063312E4: Node AEA4 is not updated

2021-01-07 10:37:49.270 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=AEA4/1, profile=0104, cluster=0019, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=B5, rssi=--, lqi=--, payload=08 79 00 02 00]

2021-01-07 10:37:49.274 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 A4 AE 01 01 19 00 B5 30 08 05 08 79 00 02 00 C2, checksum=C2, error=false) 

2021-01-07 10:37:49.297 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)

2021-01-07 10:37:49.301 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=79 state=TX_ACK

2021-01-07 10:37:49.304 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=79, state=TX_ACK, outstanding=1

2021-01-07 10:37:49.309 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=AEA4, TID=79, event=TX_ACK, state=TRANSMITTED

2021-01-07 10:37:50.902 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 B5 73)

2021-01-07 10:37:50.920 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=181)

2021-01-07 10:37:50.923 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=79 state=RX_ACK

2021-01-07 10:37:50.926 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=79, state=RX_ACK, outstanding=1

2021-01-07 10:37:50.931 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1A 44 81 00 00 19 00 A4 AE 01 01 00 4E 00 BC 58 AA 00 00 06 10 79 01 02 00 86 A4 AE 1D 31)

2021-01-07 10:37:50.931 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=AEA4, TID=79, event=RX_ACK, state=ACKED

2021-01-07 10:37:50.934 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=26, apiId=44 81, data=FE 1A 44 81 00 00 19 00 A4 AE 01 01 00 4E 00 BC 58 AA 00 00 06 10 79 01 02 00 86 A4 AE 1D 31, checksum=31, error=false

2021-01-07 10:37:50.937 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:37:50.943 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0019, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=10 79 01 02 00 86]

2021-01-07 10:37:50.947 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=121, commandId=1]

2021-01-07 10:37:50.953 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Ota Upgrade: AEA4/1 -> 0000/1, cluster=0019, TID=79, records=[ReadAttributeStatusRecord [status=UNSUPPORTED_ATTRIBUTE, attributeIdentifier=2]]]

2021-01-07 10:37:50.958 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReadAttributesResponse [Ota Upgrade: AEA4/1 -> 0000/1, cluster=0019, TID=79, records=[ReadAttributeStatusRecord [status=UNSUPPORTED_ATTRIBUTE, attributeIdentifier=2]]] 

2021-01-07 10:37:50.963 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - AEA4/1: Error reading client attribute 2 in cluster 25 - UNSUPPORTED_ATTRIBUTE

2021-01-07 10:37:50.965 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00063312E4: Could not get OTA firmware version from device

2021-01-07 10:37:50.971 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00063312E4: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet, zigbee_networkaddress=44708, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20150424, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=10, zigbee_device_initialised=true, zigbee_devices=[]}

2021-01-07 10:37:50.977 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=9783, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Ota Upgrade: 0000/0 -> AEA4/1, cluster=0019, TID=79, identifiers=[2]]]

2021-01-07 10:37:50.985 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Checking endpoint 1 channels

2021-01-07 10:37:50.990 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding

2021-01-07 10:37:50.993 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D00063312E4: transactionComplete, state=COMPLETE, outstanding=0

2021-01-07 10:37:50.997 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3

2021-01-07 10:37:51.022 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 00158D00063312E4: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level

2021-01-07 10:37:51.028 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Dynamically created 1 channels

2021-01-07 10:37:51.034 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Device initialization will be skipped as the device is already initialized

2021-01-07 10:37:51.045 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 00158D00063312E4: Level control initialized as client

2021-01-07 10:37:51.050 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Channel initialisation complete

2021-01-07 10:37:51.056 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Thing is RFD, using long poll period of 1800sec

2021-01-07 10:37:51.062 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Setting ONLINE/OFFLINE timeout interval to: 28

2021-01-07 10:37:51.067 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:usb_stick:00158d00063312e4

2021-01-07 10:37:51.071 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:usb_stick:00158d00063312e4

2021-01-07 10:37:51.076 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:usb_stick:00158d00063312e4 in 28 seconds

2021-01-07 10:37:51.089 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D00063312E4: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=0, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> AEA4/0, cluster=0033, TID=--, startIndex=0]]

2021-01-07 10:37:51.093 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3

2021-01-07 10:37:51.100 [DEBUG] [transaction.ZigBeeTransactionManager] - AEA4/0: Sending ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=12, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> AEA4/0, cluster=0033, TID=7A, startIndex=0]]

2021-01-07 10:37:51.104 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding

2021-01-07 10:37:51.109 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementBindRequest [0000/0 -> AEA4/0, cluster=0033, TID=7A, startIndex=0]

2021-01-07 10:37:51.116 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=AEA4/0, profile=0000, cluster=0033, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=B6, rssi=--, lqi=--, payload=7A 00]

2021-01-07 10:37:51.121 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 A4 AE 00 00 33 00 B6 30 08 02 7A 00 E6, checksum=E6, error=false) 

2021-01-07 10:37:51.143 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)

2021-01-07 10:37:51.147 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=7A state=TX_ACK

2021-01-07 10:37:51.151 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=7A, state=TX_ACK, outstanding=1

2021-01-07 10:37:51.155 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=AEA4, TID=7A, event=TX_ACK, state=TRANSMITTED

2021-01-07 10:37:57.105 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B3 A4 AE 84 7B)

2021-01-07 10:37:57.108 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B3, data=FE 03 45 B3 A4 AE 84 7B, checksum=7B, error=false

2021-01-07 10:37:57.111 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b3

2021-01-07 10:37:57.127 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF A4 AE 00 33 80 00 7A 00 00 84 F7)

2021-01-07 10:37:57.130 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF A4 AE 00 33 80 00 7A 00 00 84 F7, checksum=F7, error=false

2021-01-07 10:37:57.134 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/0, destinationAddress=0000/0, profile=0000, cluster=8033, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 84]

2021-01-07 10:37:57.139 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementBindResponse [AEA4/0 -> 0000/0, cluster=8033, TID=00, status=NOT_SUPPORTED, bindingTableEntries=null, startIndex=null, bindingTableList=[]]

2021-01-07 10:37:57.144 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementBindResponse [AEA4/0 -> 0000/0, cluster=8033, TID=00, status=NOT_SUPPORTED, bindingTableEntries=null, startIndex=null, bindingTableList=[]] 

2021-01-07 10:37:57.149 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction response received - waiting TX_ACK: ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=6061, state=RESPONDED, sendCnt=1, command=ManagementBindRequest [0000/0 -> AEA4/0, cluster=0033, TID=7A, startIndex=0]]

2021-01-07 10:37:59.157 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00158D00063312E4 queueTime=8069, state=COMPLETE, sendCnt=1, command=ManagementBindRequest [0000/0 -> AEA4/0, cluster=0033, TID=7A, startIndex=0]]

2021-01-07 10:37:59.161 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding

2021-01-07 10:37:59.170 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D00063312E4: transactionComplete, state=COMPLETE, outstanding=0

2021-01-07 10:37:59.171 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Error getting binding table

2021-01-07 10:37:59.174 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3

2021-01-07 10:37:59.189 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Polling initialised at 1956175ms

2021-01-07 10:37:59.197 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Done initialising ZigBee Thing handler

2021-01-07 10:37:59.204 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 00158D00063312E4: Data store: Deferring write for 250ms.

2021-01-07 10:37:59.461 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 00158D00063312E4: Data store: Writing node.

2021-01-07 10:37:59.609 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00063312E4: ZigBee saving network state complete.

==> /var/log/openhab/events.log <==

2021-01-07 10:37:59.186 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:usb_stick:00158d00063312e4' changed from UNKNOWN to ONLINE

==> /var/log/openhab/openhab.log <==

2021-01-07 10:38:00.550 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 31 44 81 00 00 00 00 A4 AE 01 01 00 44 00 69 CE AA 00 00 1D 1C 34 12 6B 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D A4 AE 1D 10)

2021-01-07 10:38:00.553 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=49, apiId=44 81, data=FE 31 44 81 00 00 00 00 A4 AE 01 01 00 44 00 69 CE AA 00 00 1D 1C 34 12 6B 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D A4 AE 1D 10, checksum=10, error=false

2021-01-07 10:38:00.556 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:00.565 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=1C 34 12 6B 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D]

2021-01-07 10:38:00.568 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=true, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=4660, sequenceNumber=107, commandId=10]

2021-01-07 10:38:00.589 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6B, reports=[AttributeReport [attributeDataType=ORDERED_SEQUENCE_STRUCTURE, attributeIdentifier=65282, attributeValue=[ZclDataPair [dataType=BOOLEAN, value=false], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=2985], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=17320], ZclDataPair [dataType=UNSIGNED_40_BIT_INTEGER, value=65543], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=102], ZclDataPair [dataType=UNSIGNED_8_BIT_INTEGER, value=93]]]]]

2021-01-07 10:38:00.594 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6B, reports=[AttributeReport [attributeDataType=ORDERED_SEQUENCE_STRUCTURE, attributeIdentifier=65282, attributeValue=[ZclDataPair [dataType=BOOLEAN, value=false], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=2985], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=17320], ZclDataPair [dataType=UNSIGNED_40_BIT_INTEGER, value=65543], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=102], ZclDataPair [dataType=UNSIGNED_8_BIT_INTEGER, value=93]]]]] 

2021-01-07 10:38:00.598 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - AEA4/1: Unknown Server attribute in 65282 cluster 0

2021-01-07 10:38:00.601 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 A4 AE 01 01 00 41 00 77 CE AA 00 00 19 18 6C 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74 A4 AE 1D FB)

2021-01-07 10:38:00.605 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 A4 AE 01 01 00 41 00 77 CE AA 00 00 19 18 6C 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74 A4 AE 1D FB, checksum=FB, error=false

2021-01-07 10:38:00.608 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:00.616 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 6C 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74]

2021-01-07 10:38:00.619 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=108, commandId=10]

2021-01-07 10:38:00.624 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6C, reports=[AttributeReport [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_magnet]]]

2021-01-07 10:38:00.628 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6C, reports=[AttributeReport [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_magnet]]] 

2021-01-07 10:38:04.701 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 31 44 81 00 00 00 00 A4 AE 01 01 00 46 00 15 01 AB 00 00 1D 1C 34 12 6D 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D A4 AE 1D A6)

2021-01-07 10:38:04.705 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=49, apiId=44 81, data=FE 31 44 81 00 00 00 00 A4 AE 01 01 00 46 00 15 01 AB 00 00 1D 1C 34 12 6D 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D A4 AE 1D A6, checksum=A6, error=false

2021-01-07 10:38:04.708 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:04.716 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=1C 34 12 6D 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D]

2021-01-07 10:38:04.720 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=true, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=4660, sequenceNumber=109, commandId=10]

2021-01-07 10:38:04.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6D, reports=[AttributeReport [attributeDataType=ORDERED_SEQUENCE_STRUCTURE, attributeIdentifier=65282, attributeValue=[ZclDataPair [dataType=BOOLEAN, value=false], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=2985], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=17320], ZclDataPair [dataType=UNSIGNED_40_BIT_INTEGER, value=65543], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=102], ZclDataPair [dataType=UNSIGNED_8_BIT_INTEGER, value=93]]]]]

2021-01-07 10:38:04.729 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6D, reports=[AttributeReport [attributeDataType=ORDERED_SEQUENCE_STRUCTURE, attributeIdentifier=65282, attributeValue=[ZclDataPair [dataType=BOOLEAN, value=false], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=2985], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=17320], ZclDataPair [dataType=UNSIGNED_40_BIT_INTEGER, value=65543], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=102], ZclDataPair [dataType=UNSIGNED_8_BIT_INTEGER, value=93]]]]] 

2021-01-07 10:38:04.733 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - AEA4/1: Unknown Server attribute in 65282 cluster 0

2021-01-07 10:38:04.736 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 A4 AE 01 01 00 46 00 23 01 AB 00 00 19 18 6E 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74 A4 AE 1D 64)

2021-01-07 10:38:04.739 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 A4 AE 01 01 00 46 00 23 01 AB 00 00 19 18 6E 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74 A4 AE 1D 64, checksum=64, error=false

2021-01-07 10:38:04.743 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:04.751 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 6E 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74]

2021-01-07 10:38:04.755 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=110, commandId=10]

2021-01-07 10:38:04.759 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6E, reports=[AttributeReport [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_magnet]]]

2021-01-07 10:38:04.764 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6E, reports=[AttributeReport [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_magnet]]] 

2021-01-07 10:38:08.504 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 31 44 81 00 00 00 00 A4 AE 01 01 00 2A 00 7C 2F AB 00 00 1D 1C 34 12 6F 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D A4 AE 1D 8F)

2021-01-07 10:38:08.507 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=49, apiId=44 81, data=FE 31 44 81 00 00 00 00 A4 AE 01 01 00 2A 00 7C 2F AB 00 00 1D 1C 34 12 6F 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D A4 AE 1D 8F, checksum=8F, error=false

2021-01-07 10:38:08.511 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:08.523 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=1C 34 12 6F 0A 02 FF 4C 06 00 10 00 21 A9 0B 21 A8 43 24 07 00 01 00 00 21 66 00 20 5D]

2021-01-07 10:38:08.527 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=true, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=4660, sequenceNumber=111, commandId=10]

2021-01-07 10:38:08.532 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6F, reports=[AttributeReport [attributeDataType=ORDERED_SEQUENCE_STRUCTURE, attributeIdentifier=65282, attributeValue=[ZclDataPair [dataType=BOOLEAN, value=false], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=2985], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=17320], ZclDataPair [dataType=UNSIGNED_40_BIT_INTEGER, value=65543], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=102], ZclDataPair [dataType=UNSIGNED_8_BIT_INTEGER, value=93]]]]]

2021-01-07 10:38:08.538 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=6F, reports=[AttributeReport [attributeDataType=ORDERED_SEQUENCE_STRUCTURE, attributeIdentifier=65282, attributeValue=[ZclDataPair [dataType=BOOLEAN, value=false], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=2985], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=17320], ZclDataPair [dataType=UNSIGNED_40_BIT_INTEGER, value=65543], ZclDataPair [dataType=UNSIGNED_16_BIT_INTEGER, value=102], ZclDataPair [dataType=UNSIGNED_8_BIT_INTEGER, value=93]]]]] 

2021-01-07 10:38:08.543 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - AEA4/1: Unknown Server attribute in 65282 cluster 0

2021-01-07 10:38:08.544 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 A4 AE 01 01 00 2C 00 8A 2F AB 00 00 19 18 70 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74 A4 AE 1D 97)

2021-01-07 10:38:08.547 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 A4 AE 01 01 00 2C 00 8A 2F AB 00 00 19 18 70 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74 A4 AE 1D 97, checksum=97, error=false

2021-01-07 10:38:08.551 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:08.561 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 70 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 61 67 6E 65 74]

2021-01-07 10:38:08.565 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=112, commandId=10]

2021-01-07 10:38:08.570 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=70, reports=[AttributeReport [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_magnet]]]

2021-01-07 10:38:08.575 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Basic: AEA4/1 -> 0000/1, cluster=0000, TID=70, reports=[AttributeReport [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_magnet]]] 

2021-01-07 10:38:19.080 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:usb_stick:00158d00063312e4

2021-01-07 10:38:19.086 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Polling stopped

2021-01-07 10:38:19.091 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00063312E4: Polling initialised at 1833916ms

2021-01-07 10:38:24.724 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 6B 00 98 F5 AB 00 00 07 18 71 0A 00 00 10 01 A4 AE 1D 1D)

2021-01-07 10:38:24.727 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 6B 00 98 F5 AB 00 00 07 18 71 0A 00 00 10 01 A4 AE 1D 1D, checksum=1D, error=false

2021-01-07 10:38:24.730 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:24.736 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 71 0A 00 00 10 01]

2021-01-07 10:38:24.740 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=113, commandId=10]

2021-01-07 10:38:24.744 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=71, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]

2021-01-07 10:38:24.749 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=71, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]] 

2021-01-07 10:38:26.320 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 46 00 14 09 AC 00 00 07 18 72 0A 00 00 10 00 A4 AE 1D 45)

2021-01-07 10:38:26.324 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 46 00 14 09 AC 00 00 07 18 72 0A 00 00 10 00 A4 AE 1D 45, checksum=45, error=false

2021-01-07 10:38:26.327 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:26.332 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 72 0A 00 00 10 00]

2021-01-07 10:38:26.335 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=114, commandId=10]

2021-01-07 10:38:26.339 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=72, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]]

2021-01-07 10:38:26.343 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=72, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]] 

2021-01-07 10:38:30.386 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 15 00 B2 3A AC 00 00 07 18 73 0A 00 00 10 01 A4 AE 1D 83)

2021-01-07 10:38:30.389 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 15 00 B2 3A AC 00 00 07 18 73 0A 00 00 10 01 A4 AE 1D 83, checksum=83, error=false

2021-01-07 10:38:30.396 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:30.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 73 0A 00 00 10 01]

2021-01-07 10:38:30.404 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=115, commandId=10]

2021-01-07 10:38:30.409 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=73, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]

2021-01-07 10:38:30.413 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=73, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]] 

2021-01-07 10:38:31.928 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 24 00 88 4D AC 00 00 07 18 74 0A 00 00 10 00 A4 AE 1D F9)

2021-01-07 10:38:31.931 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 24 00 88 4D AC 00 00 07 18 74 0A 00 00 10 00 A4 AE 1D F9, checksum=F9, error=false

2021-01-07 10:38:31.934 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:31.939 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 74 0A 00 00 10 00]

2021-01-07 10:38:31.942 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=116, commandId=10]

2021-01-07 10:38:31.946 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=74, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]]

2021-01-07 10:38:31.950 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=74, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]] 

2021-01-07 10:38:38.390 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 22 00 6B 9C AC 00 00 07 18 75 0A 00 00 10 01 A4 AE 1D CD)

2021-01-07 10:38:38.394 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 22 00 6B 9C AC 00 00 07 18 75 0A 00 00 10 01 A4 AE 1D CD, checksum=CD, error=false

2021-01-07 10:38:38.396 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:38.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 75 0A 00 00 10 01]

2021-01-07 10:38:38.405 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=117, commandId=10]

2021-01-07 10:38:38.409 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=75, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]

2021-01-07 10:38:38.414 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=75, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]] 

2021-01-07 10:38:39.670 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 22 00 0A AC AC 00 00 07 18 76 0A 00 00 10 00 A4 AE 1D 9E)

2021-01-07 10:38:39.674 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 22 00 0A AC AC 00 00 07 18 76 0A 00 00 10 00 A4 AE 1D 9E, checksum=9E, error=false

2021-01-07 10:38:39.677 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:39.682 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 76 0A 00 00 10 00]

2021-01-07 10:38:39.686 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=118, commandId=10]

2021-01-07 10:38:39.690 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=76, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]]

2021-01-07 10:38:39.695 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=76, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]] 

2021-01-07 10:38:47.154 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 46 00 63 07 AD 00 00 07 18 77 0A 00 00 10 01 A4 AE 1D 39)

2021-01-07 10:38:47.158 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 46 00 63 07 AD 00 00 07 18 77 0A 00 00 10 01 A4 AE 1D 39, checksum=39, error=false

2021-01-07 10:38:47.161 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:47.167 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 77 0A 00 00 10 01]

2021-01-07 10:38:47.170 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=119, commandId=10]

2021-01-07 10:38:47.175 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=77, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]

2021-01-07 10:38:47.179 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=77, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]] 

2021-01-07 10:38:49.095 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker Timeout has been reached for thingUID=zigbee:device:usb_stick:00158d00063312e4

2021-01-07 10:38:49.130 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 46 00 85 1F AD 00 00 07 18 78 0A 00 00 10 00 A4 AE 1D C9)

2021-01-07 10:38:49.134 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 A4 AE 01 01 00 46 00 85 1F AD 00 00 07 18 78 0A 00 00 10 00 A4 AE 1D C9, checksum=C9, error=false

2021-01-07 10:38:49.136 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4481

2021-01-07 10:38:49.141 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEA4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=18 78 0A 00 00 10 00]

2021-01-07 10:38:49.145 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=120, commandId=10]

2021-01-07 10:38:49.149 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=78, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]]

2021-01-07 10:38:49.153 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: AEA4/1 -> 0000/1, cluster=0006, TID=78, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]] 

==> /var/log/openhab/events.log <==

2021-01-07 10:38:49.106 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:usb_stick:00158d00063312e4' changed from ONLINE to OFFLINE