jonathanchristison/openhab.log

## openhab.log
21:34:32.805 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from REMOVING to REMOVED
21:34:32.807 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Handler dispose.
21:34:32.807 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Polling stopped
21:34:32.808 [DEBUG] [.converter.ZigBeeConverterSwitchOnoff] - 00158D00020B3D32: Closing device on/off cluster
21:34:32.808 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from REMOVED to UNINITIALIZED
21:34:32.811 [DEBUG] [org.openhab.binding.zigbee           ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=318, service.bundleid=220, service.scope=singleton} - org.openhab.binding.zigbee
21:34:32.813 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
21:34:32.816 [INFO ] [ome.event.ItemChannelLinkRemovedEvent] - Link 'zigbee_device_686111d4_00158d00020b3d32_00158D00020B3D32_1_switch => zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch' has been removed.
21:34:32.908 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:36.804 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:686111d4
21:34:36.805 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
21:34:36.805 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=5A, permitDuration=60, tcSignificance=true]
21:34:36.805 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=90, payload=00 3C 01]
21:34:36.806 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FC FF 00 00 36 00 5A 30 1F 03 00 3C 01 56, checksum=56, error=false)
21:34:36.917 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:36.918 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=5B, permitDuration=60, tcSignificance=true]
21:34:36.918 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=91, payload=00 3C 01]
21:34:36.918 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 5B 30 1F 03 00 3C 01 54, checksum=54, error=false)
21:34:37.038 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:37.038 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 3C B3)
21:34:37.038 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 3C B3, checksum=B3, error=false
21:34:37.038 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
21:34:37.038 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
21:34:37.039 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
21:34:37.039 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
21:34:37.039 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06)
21:34:37.039 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06, checksum=06, error=false
21:34:37.039 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00]
21:34:37.039 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
21:34:38.002 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 F5 49 01 01 00 6E 00 75 46 3E 00 00 07 18 61 0A 00 00 18 01 F5 49 1D CF)
21:34:38.002 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 F5 49 01 01 00 6E 00 75 46 3E 00 00 07 18 61 0A 00 00 18 01 F5 49 1D CF, checksum=CF, error=false
21:34:38.003 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=18933/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 61 0A 00 00 18 01]
21:34:38.003 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=97, commandId=10]
21:34:38.003 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 18933/1 -> 0/1, cluster=0406, TID=61, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:34:38.003 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 18933/1: Cluster 1030 not found for attribute response
21:34:38.004 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 18933: NWK Discovery scheduling node discovery
21:34:38.004 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 18933: NWK Discovery starting node discovery
21:34:38.004 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=5C, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:34:38.004 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=18933/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=92, payload=00 F5 49 01 00]
21:34:38.004 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 F5 49 00 00 01 00 5C 30 1F 05 00 F5 49 01 00 5C, checksum=5C, error=false)
21:34:38.122 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:40.789 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ManagementLeaveRequest [0/0 -> 18933/0, cluster=0034, TID=59, deviceAddress=00158D00020B3D32, removeChildrenRejoin=false]
21:34:40.789 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 00158D00020B3D32: No successful response received to leave command (status code 65535)
21:34:43.050 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 F5 49 01 01 00 86 00 14 84 3E 00 00 07 18 62 0A 00 00 18 01 F5 49 1D 87)
21:34:43.050 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 F5 49 01 01 00 86 00 14 84 3E 00 00 07 18 62 0A 00 00 18 01 F5 49 1D 87, checksum=87, error=false
21:34:43.051 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=18933/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 62 0A 00 00 18 01]
21:34:43.051 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=98, commandId=10]
21:34:43.051 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 18933/1 -> 0/1, cluster=0406, TID=62, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:34:43.052 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 18933/1: Cluster 1030 not found for attribute response
21:34:46.004 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=5C, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:34:47.505 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=5D, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:34:47.505 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=18933/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=93, payload=00 F5 49 01 00]
21:34:47.505 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 F5 49 00 00 01 00 5D 30 1F 05 00 F5 49 01 00 5D, checksum=5D, error=false)
21:34:47.615 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:51.777 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 C9 F5 49 32 3D 0B 02 00 8D 15 00 00 00 00 A3)
21:34:51.777 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 C9, data=FE 0D 45 C9 F5 49 32 3D 0B 02 00 8D 15 00 00 00 00 A3, checksum=A3, error=false
21:34:51.778 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c9
21:34:52.872 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA D2 C1 32 3D 0B 02 00 8D 15 00 00 00 0E)
21:34:52.873 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA D2 C1 32 3D 0B 02 00 8D 15 00 00 00 0E, checksum=0E, error=false
21:34:52.873 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
21:34:53.475 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 C1 D2 C1 D2 C1 32 3D 0B 02 00 8D 15 00 80 97)
21:34:53.475 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 C1, data=FE 0D 45 C1 D2 C1 D2 C1 32 3D 0B 02 00 8D 15 00 80 97, checksum=97, error=false
21:34:53.476 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 D2 C1 32 3D 0B 02 00 8D 15 00 80]
21:34:53.477 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [49618/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=49618, ieeeAddr=00158D00020B3D32, capability=128]
21:34:53.477 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: Device announce received. NWK=49618
21:34:53.477 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D2 C1 01 13 00 00 23 00 00 D2 C1 32 3D 0B 02 00 8D 15 00 80 81)
21:34:53.477 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Mesh related command received. Triggering mesh update.
21:34:53.478 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D2 C1 01 13 00 00 23 00 00 D2 C1 32 3D 0B 02 00 8D 15 00 80 81, checksum=81, error=false
21:34:53.478 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Start mesh update task with interval of 86400 seconds
21:34:53.478 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 00158D00020B3D32: Node 49618 added to the network
21:34:53.478 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=35, payload=00 D2 C1 32 3D 0B 02 00 8D 15 00 80]
21:34:53.478 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [49618/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=49618, ieeeAddr=00158D00020B3D32, capability=128]
21:34:53.478 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Adding discoverer for 00158D00020B3D32
21:34:53.478 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: Device announce received. NWK=49618
21:34:53.478 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Mesh related command received. Triggering mesh update.
21:34:53.478 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 71 00 24 03 3F 00 00 19 18 00 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D A6)
21:34:53.478 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: start discovery
21:34:53.478 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 00158D00020B3D32: Node 49618 update
21:34:53.479 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 71 00 24 03 3F 00 00 19 18 00 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D A6, checksum=A6, error=false
21:34:53.479 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Start mesh update task with interval of 86400 seconds
21:34:53.479 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, POWER_DESCRIPTOR, NODE_DESCRIPTOR, NEIGHBORS]
21:34:53.479 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 00 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:34:53.479 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=0, commandId=10]
21:34:53.479 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=00, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:34:53.479 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
21:34:53.479 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 00 00 D2 C1 01 01 00 6E 00 31 03 3F 00 00 07 18 01 0A 01 00 20 0B D2 C1 1D 9E)
21:34:53.479 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 00 00 D2 C1 01 01 00 6E 00 31 03 3F 00 00 07 18 01 0A 01 00 20 0B D2 C1 1D 9E, checksum=9E, error=false
21:34:53.480 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 01 0A 01 00 20 0B]
21:34:53.480 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=1, commandId=10]
21:34:53.480 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=01, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, attributeValue=11]]
21:34:53.481 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 31 44 81 00 00 00 00 D2 C1 01 01 00 71 00 3D 03 3F 00 00 1D 1C 34 12 02 0A 02 FF 4C 06 00 10 01 21 D1 0B 21 A8 01 24 00 00 00 00 00 21 3C 00 20 5E D2 C1 1D 24)
21:34:53.481 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=49, apiId=44 81, data=FE 31 44 81 00 00 00 00 D2 C1 01 01 00 71 00 3D 03 3F 00 00 1D 1C 34 12 02 0A 02 FF 4C 06 00 10 01 21 D1 0B 21 A8 01 24 00 00 00 00 00 21 3C 00 20 5E D2 C1 1D 24, checksum=24, error=false
21:34:53.481 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=1C 34 12 02 0A 02 FF 4C 06 00 10 01 21 D1 0B 21 A8 01 24 00 00 00 00 00 21 3C 00 20 5E]
21:34:53.481 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=true, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=4660, sequenceNumber=2, commandId=10]
21:34:53.481 [ERROR] [etworkManager$AFMessageListenerFilter] - Error AF message listener notify.
java.lang.NullPointerException: null
        at com.zsmartsystems.zigbee.serialization.DefaultDeserializer.readZigBeeType(DefaultDeserializer.java:72) ~[208:com.zsmartsystems.zigbee:1.1.6]
        at com.zsmartsystems.zigbee.zcl.field.AttributeReport.deserialize(AttributeReport.java:100) ~[208:com.zsmartsystems.zigbee:1.1.6]
        at com.zsmartsystems.zigbee.zcl.ZclFieldDeserializer.deserialize(ZclFieldDeserializer.java:73) ~[208:com.zsmartsystems.zigbee:1.1.6]
        at com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand.deserialize(ReportAttributesCommand.java:88) ~[208:com.zsmartsystems.zigbee:1.1.6]
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.receiveZclCommand(ZigBeeNetworkManager.java:808) ~[208:com.zsmartsystems.zigbee:1.1.6]
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.receiveCommand(ZigBeeNetworkManager.java:731) ~[208:com.zsmartsystems.zigbee:1.1.6]
        at com.zsmartsystems.zigbee.dongle.cc2531.ZigBeeDongleTiCc2531.notify(ZigBeeDongleTiCc2531.java:316) ~[209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at com.zsmartsystems.zigbee.dongle.cc2531.network.ZigBeeNetworkManager$AFMessageListenerFilter.receivedAsynchronousCommand(ZigBeeNetworkManager.java:1288) [209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at com.zsmartsystems.zigbee.dongle.cc2531.network.impl.CommandInterfaceImpl.notifyAsynchronousCommand(CommandInterfaceImpl.java:382) [209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at com.zsmartsystems.zigbee.dongle.cc2531.network.impl.CommandInterfaceImpl.handlePacket(CommandInterfaceImpl.java:160) [209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketParser.run(ZToolPacketParser.java:116) [209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at java.lang.Thread.run(Thread.java:748) [?:?]
21:34:53.484 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
21:34:53.485 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
21:34:53.488 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Starting ZigBee device discovery
21:34:53.489 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
21:34:53.489 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:686111d4
21:34:53.489 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update
21:34:53.489 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 00158D00020B3D32
21:34:53.489 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: Update mesh
21:34:53.490 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: already scheduled or running
21:34:53.490 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, POWER_DESCRIPTOR, NODE_DESCRIPTOR, NEIGHBORS, ROUTES]
21:34:53.490 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 00124B0018ED2A82
21:34:53.490 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: Update mesh
21:34:53.490 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: scheduled [NEIGHBORS, ROUTES]
21:34:53.491 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'zigbee:device:686111d4:00158d00020b3d32' to inbox.
21:34:53.491 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Node discovery not complete
21:34:53.491 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'zigbee:device:686111d4:00158d00020b3d32' has been added.
21:34:53.494 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Starting ZigBee device discovery
21:34:53.495 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:686111d4
21:34:53.496 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Node discovery not complete
21:34:53.790 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: running
21:34:53.791 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=5E, startIndex=0]
21:34:53.791 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=94, payload=00 00]
21:34:53.791 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 31 00 5E 30 1F 02 00 00 6B, checksum=6B, error=false)
21:34:53.910 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:53.911 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 32 45 B1 00 00 00 02 00 02 64 AD A0 90 73 EE B4 C4 32 3D 0B 02 00 8D 15 00 D2 C1 12 02 01 73 64 AD A0 90 73 EE B4 C4 AB 95 97 FE FF 57 0B 00 DE DD 12 02 01 6A A5)
21:34:53.911 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=50, apiId=45 B1, data=FE 32 45 B1 00 00 00 02 00 02 64 AD A0 90 73 EE B4 C4 32 3D 0B 02 00 8D 15 00 D2 C1 12 02 01 73 64 AD A0 90 73 EE B4 C4 AB 95 97 FE FF 57 0B 00 DE DD 12 02 01 6A A5, checksum=A5, error=false
21:34:53.911 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 02 00 02 64 AD A0 90 73 EE B4 C4 32 3D 0B 02 00 8D 15 00 D2 C1 12 02 01 73 64 AD A0 90 73 EE B4 C4 AB 95 97 FE FF 57 0B 00 DE DD 12 02 01 6A]
21:34:53.911 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=2, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00158D00020B3D32, networkAddress=49618, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=115], NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=000B57FFFE9795AB, networkAddress=56798, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=106]]]
21:34:53.912 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=5E, startIndex=0]
21:34:53.912 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 39 45 FF 00 00 00 31 80 00 00 00 00 00 02 00 02 64 AD A0 90 73 EE B4 C4 32 3D 0B 02 00 8D 15 00 D2 C1 12 02 01 73 64 AD A0 90 73 EE B4 C4 AB 95 97 FE FF 57 0B 00 DE DD 12 02 01 6A 51)
21:34:53.912 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=57, apiId=45 FF, data=FE 39 45 FF 00 00 00 31 80 00 00 00 00 00 02 00 02 64 AD A0 90 73 EE B4 C4 32 3D 0B 02 00 8D 15 00 D2 C1 12 02 01 73 64 AD A0 90 73 EE B4 C4 AB 95 97 FE FF 57 0B 00 DE DD 12 02 01 6A 51, checksum=51, error=false
21:34:53.912 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=2, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00158D00020B3D32, networkAddress=49618, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=115], NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=000B57FFFE9795AB, networkAddress=56798, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=106]]]]
21:34:53.912 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 02 00 02 64 AD A0 90 73 EE B4 C4 32 3D 0B 02 00 8D 15 00 D2 C1 12 02 01 73 64 AD A0 90 73 EE B4 C4 AB 95 97 FE FF 57 0B 00 DE DD 12 02 01 6A]
21:34:53.912 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: ManagementLqiRequest complete [2 neighbors]
21:34:53.913 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=2, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00158D00020B3D32, networkAddress=49618, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=115], NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=000B57FFFE9795AB, networkAddress=56798, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=106]]]
21:34:53.913 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 00124B0018ED2A82: Neighbor table updated: [NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00158D00020B3D32, networkAddress=49618, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=115], NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=000B57FFFE9795AB, networkAddress=56798, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=106]]
21:34:53.913 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: request NEIGHBORS successful. Advanced to ROUTES.
21:34:53.913 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: running
21:34:53.913 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=5F, startIndex=0]
21:34:53.913 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=95, payload=00 00]
21:34:53.913 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 32 00 5F 30 1F 02 00 00 69, checksum=69, error=false)
21:34:54.030 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:54.031 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B2 00 00 00 00 00 00 F1)
21:34:54.031 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B2, data=FE 06 45 B2 00 00 00 00 00 00 F1, checksum=F1, error=false
21:34:54.031 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 00 00 00]
21:34:54.032 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]
21:34:54.032 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05)
21:34:54.032 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=5F, startIndex=0]
21:34:54.032 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05, checksum=05, error=false
21:34:54.032 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]]
21:34:54.032 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 00 00 00]
21:34:54.033 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: ManagementLqiRequest complete [0 routes]
21:34:54.033 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]
21:34:54.033 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 00124B0018ED2A82: Routing table NEW: []
21:34:54.033 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 00124B0018ED2A82: Routing table OLD: []
21:34:54.033 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 00124B0018ED2A82: Routing table unchanged
21:34:54.033 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: request ROUTES successful. Advanced to null.
21:34:54.033 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: running
21:34:54.033 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00124B0018ED2A82: Node SVC Discovery: complete
21:34:54.034 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 00124B0018ED2A82: Node 0 update
21:34:54.035 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
21:34:54.035 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zigbee:coordinator_cc2531:686111d4' has been updated.
21:34:54.040 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
21:34:54.129 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:34:54.129 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=60, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:34:54.130 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=96, payload=00 32 3D 0B 02 00 8D 15 00 00 00]
21:34:54.130 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FF FF 00 00 00 00 60 30 1F 0B 00 32 3D 0B 02 00 8D 15 00 00 00 EA, checksum=EA, error=false)
21:34:54.243 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:55.070 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:34:55.070 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=61, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:34:55.071 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=97, payload=00 32 3D 0B 02 00 8D 15 00 00 00]
21:34:55.071 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FF FF 00 00 00 00 61 30 1F 0B 00 32 3D 0B 02 00 8D 15 00 00 00 EB, checksum=EB, error=false)
21:34:55.184 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:55.505 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=5D, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:34:56.573 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 69 00 2A 29 3F 00 00 07 18 03 0A 00 00 18 01 D2 C1 1D 9B)
21:34:56.574 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 69 00 2A 29 3F 00 00 07 18 03 0A 00 00 18 01 D2 C1 1D 9B, checksum=9B, error=false
21:34:56.574 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 03 0A 00 00 18 01]
21:34:56.574 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=3, commandId=10]
21:34:56.574 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=03, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:34:57.005 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=62, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:34:57.006 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=18933/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=98, payload=00 F5 49 01 00]
21:34:57.006 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 F5 49 00 00 01 00 62 30 1F 05 00 F5 49 01 00 62, checksum=62, error=false)
21:34:57.122 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:57.447 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 D2 33 3F 00 00 19 18 04 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 49)
21:34:57.448 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 D2 33 3F 00 00 19 18 04 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 49, checksum=49, error=false
21:34:57.448 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 04 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:34:57.449 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=4, commandId=10]
21:34:57.449 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=04, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:34:58.139 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 32 3D 0B 02 00 8D 15 00 D2 C1 00 00 45)
21:34:58.140 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 32 3D 0B 02 00 8D 15 00 D2 C1 00 00 45, checksum=45, error=false
21:34:58.140 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
21:34:58.140 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D2 C1 00 00 80 00 00 00 00 00 32 3D 0B 02 00 8D 15 00 D2 C1 B0)
21:34:58.140 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D2 C1 00 00 80 00 00 00 00 00 32 3D 0B 02 00 8D 15 00 D2 C1 B0, checksum=B0, error=false
21:34:58.140 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 32 3D 0B 02 00 8D 15 00 D2 C1]
21:34:58.141 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [49618/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00158D00020B3D32, nwkAddrRemoteDev=49618, startIndex=null, nwkAddrAssocDevList=[]]
21:34:58.141 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=60, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:34:58.141 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=61, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:34:58.141 [ERROR] [2531.network.packet.ZToolPacketStream] - Packet parsing failed due to exception.
com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolParseException: Packet checksum failed
        at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketStream.parsePacket(ZToolPacketStream.java:140) [209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketParser.run(ZToolPacketParser.java:107) [209:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
        at java.lang.Thread.run(Thread.java:748) [?:?]
21:34:58.141 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 49618: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [49618/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00158D00020B3D32, nwkAddrRemoteDev=49618, startIndex=null, nwkAddrAssocDevList=[]]
21:34:58.141 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 49618: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [49618/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00158D00020B3D32, nwkAddrRemoteDev=49618, startIndex=null, nwkAddrAssocDevList=[]]
21:34:58.141 [DEBUG] [2531.network.packet.ZToolPacketParser] - Received a BAD PACKET FE 00 00 00 00
21:34:58.141 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.
21:34:58.141 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.
21:34:58.142 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:34:58.142 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:34:58.142 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 49618/0, cluster=0005, TID=64, nwkAddrOfInterest=49618]
21:34:58.142 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 49618/0, cluster=0005, TID=63, nwkAddrOfInterest=49618]
21:34:58.142 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=99, payload=00 D2 C1]
21:34:58.142 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=100, payload=00 D2 C1]
21:34:58.142 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 D2 C1 00 00 05 00 63 30 1F 03 00 D2 C1 62, checksum=62, error=false)
21:34:58.260 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:34:58.260 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 D2 C1 00 00 05 00 64 30 1F 03 00 D2 C1 65, checksum=65, error=false)
21:34:58.380 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:00.864 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'zigbee:device:686111d4:00158d00020b3d32' has been removed.
21:35:00.866 [DEBUG] [org.openhab.binding.zigbee           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=383, service.bundleid=220, service.scope=singleton} - org.openhab.binding.zigbee
21:35:00.867 [DEBUG] [org.openhab.binding.zigbee           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.type.DynamicStateDescriptionProvider}={service.id=384, service.bundleid=220, service.scope=singleton} - org.openhab.binding.zigbee
21:35:00.870 [DEBUG] [org.openhab.binding.zigbee           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=385, service.bundleid=220, service.scope=singleton} - org.openhab.binding.zigbee
21:35:00.872 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Initializing ZigBee thing handler zigbee:device:686111d4:00158d00020b3d32
21:35:00.872 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Coordinator status changed to ONLINE.
21:35:00.873 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from UNINITIALIZED to INITIALIZING
21:35:00.873 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Coordinator is ONLINE. Starting device initialisation.
21:35:00.873 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: NWK Discovery starting node rediscovery
21:35:00.873 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=65, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:00.873 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from INITIALIZING to UNKNOWN
21:35:00.873 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=101, payload=00 32 3D 0B 02 00 8D 15 00 00 00]
21:35:00.874 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FD FF 00 00 00 00 65 30 1F 0B 00 32 3D 0B 02 00 8D 15 00 00 00 ED, checksum=ED, error=false)
21:35:00.883 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Node has not finished discovery
21:35:00.885 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from UNKNOWN to OFFLINE: Node has not completed discovery
21:35:00.891 [INFO ] [arthome.event.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:686111d4:00158d00020b3d32 changed to UNKNOWN.
21:35:00.987 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:02.489 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 61 00 02 71 3F 00 00 19 18 05 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D E7)
21:35:02.490 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 61 00 02 71 3F 00 00 19 18 05 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D E7, checksum=E7, error=false
21:35:02.490 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 05 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:02.490 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=5, commandId=10]
21:35:02.491 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=05, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:05.005 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=62, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:35:06.142 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ActiveEndpointsRequest [0/0 -> 49618/0, cluster=0005, TID=63, nwkAddrOfInterest=49618]
21:35:06.142 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ActiveEndpointsRequest [0/0 -> 49618/0, cluster=0005, TID=64, nwkAddrOfInterest=49618]
21:35:06.142 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [TIMEOUT]
21:35:06.142 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [TIMEOUT]
21:35:06.143 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 1, wait 2175ms before retry.
21:35:06.143 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 1, wait 2175ms before retry.
21:35:06.506 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=66, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:35:06.507 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=18933/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=102, payload=00 F5 49 01 00]
21:35:06.507 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 F5 49 00 00 01 00 66 30 1F 05 00 F5 49 01 00 66, checksum=66, error=false)
21:35:06.622 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:07.355 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 C5 AC 3F 00 00 19 18 06 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D C3)
21:35:07.355 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 C5 AC 3F 00 00 19 18 06 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D C3, checksum=C3, error=false
21:35:07.356 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 06 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:07.356 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=6, commandId=10]
21:35:07.356 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=06, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:08.318 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:35:08.318 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 49618/0, cluster=0005, TID=67, nwkAddrOfInterest=49618]
21:35:08.319 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=103, payload=00 D2 C1]
21:35:08.319 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 D2 C1 00 00 05 00 67 30 1F 03 00 D2 C1 66, checksum=66, error=false)
21:35:08.429 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:08.658 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 07 45 85 D2 C1 00 D2 C1 01 01 C7)
21:35:08.658 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=7, apiId=45 85, data=FE 07 45 85 D2 C1 00 D2 C1 01 01 C7, checksum=C7, error=false
21:35:08.658 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 00 D2 C1 01 01]
21:35:08.659 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [49618/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, activeEpList=[1]]
21:35:08.659 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0E 45 FF D2 C1 00 05 80 00 00 00 00 00 D2 C1 01 01 31)
21:35:08.659 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 49618/0, cluster=0005, TID=67, nwkAddrOfInterest=49618]
21:35:08.660 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=14, apiId=45 FF, data=FE 0E 45 FF D2 C1 00 05 80 00 00 00 00 00 D2 C1 01 01 31, checksum=31, error=false
21:35:08.660 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 D2 C1 01 01]
21:35:08.660 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [49618/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, activeEpList=[1]]]
21:35:08.660 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [49618/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, activeEpList=[1]]
21:35:08.660 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 49618/0, cluster=0004, TID=68, nwkAddrOfInterest=49618, endpoint=1]
21:35:08.660 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=104, payload=00 D2 C1 01]
21:35:08.661 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=14, apiId=24 01, data=FE 0E 24 01 D2 C1 00 00 04 00 68 30 1F 04 00 D2 C1 01 6D, checksum=6D, error=false)
21:35:08.778 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:08.873 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=65, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:08.874 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: NWK Discovery node rediscovery request failed. Wait before retry.
21:35:10.374 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=69, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:10.375 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=105, payload=00 32 3D 0B 02 00 8D 15 00 00 00]
21:35:10.375 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FD FF 00 00 00 00 69 30 1F 0B 00 32 3D 0B 02 00 8D 15 00 00 00 E1, checksum=E1, error=false)
21:35:10.485 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:11.086 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 59 00 50 DA 3F 00 00 19 18 07 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 24)
21:35:11.086 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 59 00 50 DA 3F 00 00 19 18 07 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 24, checksum=24, error=false
21:35:11.086 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 07 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:11.087 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=7, commandId=10]
21:35:11.087 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=07, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:12.274 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 24 45 84 D2 C1 00 D2 C1 1E 01 04 01 04 01 01 04 00 00 03 00 FF FF 19 00 07 00 00 04 00 03 00 06 00 08 00 05 00 19 00 F7)
21:35:12.274 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=36, apiId=45 84, data=FE 24 45 84 D2 C1 00 D2 C1 1E 01 04 01 04 01 01 04 00 00 03 00 FF FF 19 00 07 00 00 04 00 03 00 06 00 08 00 05 00 19 00 F7, checksum=F7, error=false
21:35:12.274 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 00 D2 C1 1E 01 04 01 04 01 01 04 00 00 03 00 FF FF 19 00 07 00 00 04 00 03 00 06 00 08 00 05 00 19 00]
21:35:12.275 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [49618/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, length=30, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=260, deviceVersion=1, inputClusterList=[0, 3, 65535, 25], outputClusterList=[0, 4, 3, 6, 8, 5, 25]]]
21:35:12.276 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 2B 45 FF D2 C1 00 04 80 00 00 00 00 00 D2 C1 1E 01 04 01 04 01 01 04 00 00 03 00 FF FF 19 00 07 00 00 04 00 03 00 06 00 08 00 05 00 19 00 07)
21:35:12.276 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: SimpleDescriptorRequest [0/0 -> 49618/0, cluster=0004, TID=68, nwkAddrOfInterest=49618, endpoint=1]
21:35:12.276 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=43, apiId=45 FF, data=FE 2B 45 FF D2 C1 00 04 80 00 00 00 00 00 D2 C1 1E 01 04 01 04 01 01 04 00 00 03 00 FF FF 19 00 07 00 00 04 00 03 00 06 00 08 00 05 00 19 00 07, checksum=07, error=false
21:35:12.276 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 D2 C1 1E 01 04 01 04 01 01 04 00 00 03 00 FF FF 19 00 07 00 00 04 00 03 00 06 00 08 00 05 00 19 00]
21:35:12.276 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: SimpleDescriptorResponse returned SimpleDescriptorResponse [49618/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, length=30, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=260, deviceVersion=1, inputClusterList=[0, 3, 65535, 25], outputClusterList=[0, 4, 3, 6, 8, 5, 25]]]
21:35:12.276 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [49618/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, length=30, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=260, deviceVersion=1, inputClusterList=[0, 3, 65535, 25], outputClusterList=[0, 4, 3, 6, 8, 5, 25]]]
21:35:12.276 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting input clusters [0, 3, 65535, 25]
21:35:12.277 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster BASIC as server
21:35:12.277 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster IDENTIFY as server
21:35:12.277 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster GENERAL as server
21:35:12.277 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster OTA_UPGRADE as server
21:35:12.277 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting output clusters [0, 4, 3, 6, 8, 5, 25]
21:35:12.277 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster BASIC as client
21:35:12.278 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster GROUPS as client
21:35:12.278 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster IDENTIFY as client
21:35:12.278 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster ON_OFF as client
21:35:12.278 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster LEVEL_CONTROL as client
21:35:12.278 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster SCENES as client
21:35:12.278 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Setting cluster OTA_UPGRADE as client
21:35:12.278 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request ACTIVE_ENDPOINTS successful. Advanced to POWER_DESCRIPTOR.
21:35:12.279 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:35:12.279 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: PowerDescriptorRequest [0/0 -> 49618/0, cluster=0003, TID=6A, nwkAddrOfInterest=49618]
21:35:12.279 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=3, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=106, payload=00 D2 C1]
21:35:12.279 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 D2 C1 00 00 03 00 6A 30 1F 03 00 D2 C1 6D, checksum=6D, error=false)
21:35:12.394 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:14.506 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: IeeeAddressRequest [0/0 -> 18933/0, cluster=0001, TID=66, nwkAddrOfInterest=18933, requestType=1, startIndex=0]
21:35:16.007 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 18933: NWK Discovery ending node discovery
21:35:16.644 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 2A 1E 40 00 00 19 18 08 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D EF)
21:35:16.645 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 2A 1E 40 00 00 19 18 08 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D EF, checksum=EF, error=false
21:35:16.645 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 08 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:16.645 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=8, commandId=10]
21:35:16.645 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=08, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:17.707 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 07 45 83 D2 C1 00 D2 C1 40 C4 45)
21:35:17.708 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=7, apiId=45 83, data=FE 07 45 83 D2 C1 00 D2 C1 40 C4 45, checksum=45, error=false
21:35:17.708 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 00 D2 C1 40 C4]
21:35:17.709 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [49618/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, powerDescriptor=RECEIVER_ON_IDLE, [DISPOSABLE_BATTERY], DISPOSABLE_BATTERY, FULL]
21:35:17.709 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0E 45 FF D2 C1 00 03 80 00 00 00 00 00 D2 C1 40 C4 B3)
21:35:17.709 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: PowerDescriptorRequest [0/0 -> 49618/0, cluster=0003, TID=6A, nwkAddrOfInterest=49618]
21:35:17.709 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=14, apiId=45 FF, data=FE 0E 45 FF D2 C1 00 03 80 00 00 00 00 00 D2 C1 40 C4 B3, checksum=B3, error=false
21:35:17.709 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: PowerDescriptorResponse returned PowerDescriptorResponse [49618/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, powerDescriptor=RECEIVER_ON_IDLE, [DISPOSABLE_BATTERY], DISPOSABLE_BATTERY, FULL]
21:35:17.709 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 D2 C1 40 C4]
21:35:17.710 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request POWER_DESCRIPTOR successful. Advanced to NODE_DESCRIPTOR.
21:35:17.710 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [49618/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, powerDescriptor=RECEIVER_ON_IDLE, [DISPOSABLE_BATTERY], DISPOSABLE_BATTERY, FULL]
21:35:17.710 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:35:17.710 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NodeDescriptorRequest [0/0 -> 49618/0, cluster=0002, TID=6B, nwkAddrOfInterest=49618]
21:35:17.710 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=2, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=107, payload=00 D2 C1]
21:35:17.710 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 D2 C1 00 00 02 00 6B 30 1F 03 00 D2 C1 6D, checksum=6D, error=false)
21:35:17.828 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:18.374 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=69, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:18.375 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: NWK Discovery node rediscovery request failed. Wait before retry.
21:35:19.875 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=6C, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:19.876 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=108, payload=00 32 3D 0B 02 00 8D 15 00 00 00]
21:35:19.876 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FD FF 00 00 00 00 6C 30 1F 0B 00 32 3D 0B 02 00 8D 15 00 00 00 E4, checksum=E4, error=false)
21:35:19.986 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:22.039 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5E 00 08 60 40 00 00 19 18 09 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D B0)
21:35:22.040 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5E 00 08 60 40 00 00 19 18 09 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D B0, checksum=B0, error=false
21:35:22.040 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 09 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:22.040 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=9, commandId=10]
21:35:22.041 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=09, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:22.980 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 12 45 82 D2 C1 00 D2 C1 02 40 80 37 10 7F 64 00 00 00 64 00 00 4F)
21:35:22.980 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=18, apiId=45 82, data=FE 12 45 82 D2 C1 00 D2 C1 02 40 80 37 10 7F 64 00 00 00 64 00 00 4F, checksum=4F, error=false
21:35:22.981 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 00 D2 C1 02 40 80 37 10 7F 64 00 00 00 64 00 00]
21:35:22.981 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [49618/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=127, complexDescriptorAvailable=false, manufacturerCode=4151, logicalType=END_DEVICE, serverCapabilities=[], incomingTransferSize=100, outgoingTransferSize=100, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[REDUCED_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false, stackCompliance=0]]
21:35:22.982 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 19 45 FF D2 C1 00 02 80 00 00 00 00 00 D2 C1 02 40 80 37 10 7F 64 00 00 00 64 00 00 BB)
21:35:22.982 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: NodeDescriptorRequest [0/0 -> 49618/0, cluster=0002, TID=6B, nwkAddrOfInterest=49618]
21:35:22.982 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=45 FF, data=FE 19 45 FF D2 C1 00 02 80 00 00 00 00 00 D2 C1 02 40 80 37 10 7F 64 00 00 00 64 00 00 BB, checksum=BB, error=false
21:35:22.982 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 D2 C1 02 40 80 37 10 7F 64 00 00 00 64 00 00]
21:35:22.982 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: NodeDescriptorResponse returned NodeDescriptorResponse [49618/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=127, complexDescriptorAvailable=false, manufacturerCode=4151, logicalType=END_DEVICE, serverCapabilities=[], incomingTransferSize=100, outgoingTransferSize=100, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[REDUCED_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false, stackCompliance=0]]
21:35:22.983 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [49618/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=49618, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=127, complexDescriptorAvailable=false, manufacturerCode=4151, logicalType=END_DEVICE, serverCapabilities=[], incomingTransferSize=100, outgoingTransferSize=100, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[REDUCED_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false, stackCompliance=0]]
21:35:22.983 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request NODE_DESCRIPTOR successful. Advanced to NEIGHBORS.
21:35:22.983 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:35:22.983 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 49618/0, cluster=0031, TID=6D, startIndex=0]
21:35:22.983 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=109, payload=00 00]
21:35:22.984 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 D2 C1 00 00 31 00 6D 30 1F 02 00 00 4B, checksum=4B, error=false)
21:35:23.100 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:26.384 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 12 95 40 00 00 19 18 0A 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 5E)
21:35:26.384 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 12 95 40 00 00 19 18 0A 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 5E, checksum=5E, error=false
21:35:26.384 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 0A 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:26.385 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=10, commandId=10]
21:35:26.385 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=0A, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:27.203 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 1C 45 B1 D2 C1 00 0A 00 01 64 AD A0 90 73 EE B4 C4 82 2A ED 18 00 4B 12 00 00 00 04 02 00 6A 8C)
21:35:27.204 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=45 B1, data=FE 1C 45 B1 D2 C1 00 0A 00 01 64 AD A0 90 73 EE B4 C4 82 2A ED 18 00 4B 12 00 00 00 04 02 00 6A 8C, checksum=8C, error=false
21:35:27.204 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 00 0A 00 01 64 AD A0 90 73 EE B4 C4 82 2A ED 18 00 4B 12 00 00 00 04 02 00 6A]
21:35:27.204 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00124B0018ED2A82, networkAddress=0, deviceType=COORDINATOR, rxOnWhenIdle=RX_ON, relationship=PARENT, permitJoining=UNKNOWN, depth=0, lqi=106]]]
21:35:27.204 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 49618/0, cluster=0031, TID=6D, startIndex=0]
21:35:27.204 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 23 45 FF D2 C1 00 31 80 00 00 00 00 00 0A 00 01 64 AD A0 90 73 EE B4 C4 82 2A ED 18 00 4B 12 00 00 00 04 02 00 6A 4C)
21:35:27.204 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=35, apiId=45 FF, data=FE 23 45 FF D2 C1 00 31 80 00 00 00 00 00 0A 00 01 64 AD A0 90 73 EE B4 C4 82 2A ED 18 00 4B 12 00 00 00 04 02 00 6A 4C, checksum=4C, error=false
21:35:27.205 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00124B0018ED2A82, networkAddress=0, deviceType=COORDINATOR, rxOnWhenIdle=RX_ON, relationship=PARENT, permitJoining=UNKNOWN, depth=0, lqi=106]]]]
21:35:27.205 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 0A 00 01 64 AD A0 90 73 EE B4 C4 82 2A ED 18 00 4B 12 00 00 00 04 02 00 6A]
21:35:27.205 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 49618/0, cluster=0031, TID=6E, startIndex=1]
21:35:27.205 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00124B0018ED2A82, networkAddress=0, deviceType=COORDINATOR, rxOnWhenIdle=RX_ON, relationship=PARENT, permitJoining=UNKNOWN, depth=0, lqi=106]]]
21:35:27.205 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=110, payload=00 01]
21:35:27.205 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 D2 C1 00 00 31 00 6E 30 1F 02 00 01 49, checksum=49, error=false)
21:35:27.323 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:27.324 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 49618/0, cluster=0031, TID=6E, startIndex=1]
21:35:27.324 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00124B0018ED2A82, networkAddress=0, deviceType=COORDINATOR, rxOnWhenIdle=RX_ON, relationship=PARENT, permitJoining=UNKNOWN, depth=0, lqi=106]]]]
21:35:27.324 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 49618/0, cluster=0031, TID=6F, startIndex=2]
21:35:27.324 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=111, payload=00 02]
21:35:27.325 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 D2 C1 00 00 31 00 6F 30 1F 02 00 02 4B, checksum=4B, error=false)
21:35:27.444 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:27.875 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=6C, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:27.876 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: NWK Discovery node rediscovery request failed. Wait before retry.
21:35:29.376 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=70, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:29.377 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=112, payload=00 32 3D 0B 02 00 8D 15 00 00 00]
21:35:29.377 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FD FF 00 00 00 00 70 30 1F 0B 00 32 3D 0B 02 00 8D 15 00 00 00 F8, checksum=F8, error=false)
21:35:29.487 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:32.994 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 59 00 C2 E5 40 00 00 19 18 0B 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D FA)
21:35:32.994 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 59 00 C2 E5 40 00 00 19 18 0B 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D FA, checksum=FA, error=false
21:35:32.995 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 0B 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:32.995 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=11, commandId=10]
21:35:32.995 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=0B, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:33.689 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B1 D2 C1 00 0A 01 00 EA)
21:35:33.690 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B1, data=FE 06 45 B1 D2 C1 00 0A 01 00 EA, checksum=EA, error=false
21:35:33.690 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 00 0A 01 00]
21:35:33.690 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=1, neighborTableList=[]]
21:35:33.691 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF D2 C1 00 31 80 00 00 00 00 00 0A 01 00 1E)
21:35:33.691 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 49618/0, cluster=0031, TID=6F, startIndex=2]
21:35:33.691 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF D2 C1 00 31 80 00 00 00 00 00 0A 01 00 1E, checksum=1E, error=false
21:35:33.691 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=1, neighborTableList=[]]]
21:35:33.691 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 0A 01 00]
21:35:33.691 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ManagementLqiRequest complete [1 neighbors]
21:35:33.691 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [49618/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=10, startIndex=1, neighborTableList=[]]
21:35:33.692 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 00158D00020B3D32: Neighbor table updated: [NeighborTable [extendedPanId=C4B4EE7390A0AD64, extendedAddress=00124B0018ED2A82, networkAddress=0, deviceType=COORDINATOR, rxOnWhenIdle=RX_ON, relationship=PARENT, permitJoining=UNKNOWN, depth=0, lqi=106]]
21:35:33.692 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request NEIGHBORS successful. Advanced to ROUTES.
21:35:33.692 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:35:33.692 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 49618/0, cluster=0032, TID=71, startIndex=0]
21:35:33.692 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=113, payload=00 00]
21:35:33.692 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 D2 C1 00 00 32 00 71 30 1F 02 00 00 54, checksum=54, error=false)
21:35:33.810 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:37.026 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 00 8F)
21:35:37.026 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 00 8F, checksum=8F, error=false
21:35:37.026 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
21:35:37.376 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=70, ieeeAddr=00158D00020B3D32, requestType=0, startIndex=0]
21:35:37.377 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: NWK Discovery node rediscovery request failed. Wait before retry.
21:35:38.877 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 00158D00020B3D32: NWK Discovery finishing node rediscovery
21:35:39.189 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 59 00 64 31 41 00 00 19 18 0C 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 8E)
21:35:39.190 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 59 00 64 31 41 00 00 19 18 0C 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 8E, checksum=8E, error=false
21:35:39.190 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 0C 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:39.190 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=12, commandId=10]
21:35:39.190 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=0C, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:39.755 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B2 D2 C1 84 63)
21:35:39.756 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B2, data=FE 03 45 B2 D2 C1 84 63, checksum=63, error=false
21:35:39.756 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=C1 84]
21:35:39.756 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [49618/0 -> 0/0, cluster=8032, TID=NULL, status=NOT_SUPPORTED, routingTableEntries=null, startIndex=null, routingTableList=[]]
21:35:39.756 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF D2 C1 00 32 80 00 00 00 00 84 95)
21:35:39.756 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 49618/0, cluster=0032, TID=71, startIndex=0]
21:35:39.757 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF D2 C1 00 32 80 00 00 00 00 84 95, checksum=95, error=false
21:35:39.757 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [ERROR (UNKNOWN,0xFF), ManagementRoutingResponse [49618/0 -> 0/0, cluster=8032, TID=NULL, status=NOT_SUPPORTED, routingTableEntries=null, startIndex=null, routingTableList=[]]]
21:35:39.757 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 84]
21:35:39.757 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery ManagementLqiRequest not supported
21:35:39.757 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [49618/0 -> 0/0, cluster=8032, TID=NULL, status=NOT_SUPPORTED, routingTableEntries=null, startIndex=null, routingTableList=[]]
21:35:39.757 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: request ROUTES successful. Advanced to null.
21:35:39.757 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: running
21:35:39.757 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D00020B3D32: Node SVC Discovery: complete
21:35:39.757 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 00158D00020B3D32: Node 49618 update
21:35:39.758 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Node updated - ZigBeeNode [IEEE=00158D00020B3D32, NWK=C1D2, Type=END_DEVICE]
21:35:39.760 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
21:35:39.760 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zigbee:device:686111d4:00158d00020b3d32' has been updated.
21:35:39.764 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
21:35:39.768 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Starting ZigBee device discovery
21:35:39.769 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:686111d4
21:35:39.769 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: ZigBee node property discovery start
21:35:39.769 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: ZigBee node property discovery using 49618/1
21:35:39.770 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Start initialising ZigBee Thing handler
21:35:39.770 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=null]
21:35:39.770 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: ZigBee node property discovery start
21:35:39.770 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=72, identifiers=[4]]
21:35:39.770 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: ZigBee node property discovery using 49618/1
21:35:39.770 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=114, commandId=0]
21:35:39.770 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=null]
21:35:39.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=114, payload=00 72 00 04 00]
21:35:39.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=73, identifiers=[4]]
21:35:39.771 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 72 30 1F 05 00 72 00 04 00 17, checksum=17, error=false)
21:35:39.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=115, commandId=0]
21:35:39.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=115, payload=00 73 00 04 00]
21:35:39.880 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:39.880 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 73 30 1F 05 00 73 00 04 00 17, checksum=17, error=false)
21:35:40.000 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:45.814 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 42 82 41 00 00 19 18 0D 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 1F)
21:35:45.814 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 5C 00 42 82 41 00 00 19 18 0D 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 1F, checksum=1F, error=false
21:35:45.814 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 0D 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:45.815 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=13, commandId=10]
21:35:45.815 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=0D, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:46.249 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 72 44)
21:35:46.249 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=114)
21:35:46.250 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 73 45)
21:35:46.250 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=115)
21:35:47.770 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=72, identifiers=[4]]
21:35:47.771 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=73, identifiers=[4]]
21:35:47.771 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Manufacturer request failed (retry 0)
21:35:47.771 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=null]
21:35:47.771 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Manufacturer request failed (retry 0)
21:35:47.771 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=null]
21:35:47.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=74, identifiers=[4]]
21:35:47.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=75, identifiers=[4]]
21:35:47.771 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=116, commandId=0]
21:35:47.772 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=117, commandId=0]
21:35:47.772 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=116, payload=00 74 00 04 00]
21:35:47.772 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=117, payload=00 75 00 04 00]
21:35:47.772 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 74 30 1F 05 00 74 00 04 00 17, checksum=17, error=false)
21:35:47.881 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:47.882 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 75 30 1F 05 00 75 00 04 00 17, checksum=17, error=false)
21:35:48.002 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:51.231 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 1A 00 64 C4 41 00 00 19 18 0E 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 3A)
21:35:51.231 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 1A 00 64 C4 41 00 00 19 18 0E 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 3A, checksum=3A, error=false
21:35:51.232 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 0E 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:51.232 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=14, commandId=10]
21:35:51.232 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=0E, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:52.411 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 74 B2)
21:35:52.412 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=116)
21:35:52.412 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 20 44 81 00 00 00 00 D2 C1 01 01 00 63 00 C0 D2 41 00 00 0C 18 74 01 04 00 00 42 04 4C 55 4D 49 D2 C1 1D F6)
21:35:52.412 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=32, apiId=44 81, data=FE 20 44 81 00 00 00 00 D2 C1 01 01 00 63 00 C0 D2 41 00 00 0C 18 74 01 04 00 00 42 04 4C 55 4D 49 D2 C1 1D F6, checksum=F6, error=false
21:35:52.412 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 74 01 04 00 00 42 04 4C 55 4D 49]
21:35:52.413 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=116, commandId=1]
21:35:52.413 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 49618/1 -> 0/1, cluster=0000, TID=74, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=LUMI]]]
21:35:52.413 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=74, identifiers=[4]]
21:35:52.414 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=3, name=HWVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
21:35:52.414 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=76, identifiers=[3]]
21:35:52.414 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=118, commandId=0]
21:35:52.414 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=118, payload=00 76 00 03 00]
21:35:52.414 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 76 30 1F 05 00 76 00 03 00 10, checksum=10, error=false)
21:35:52.532 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:53.088 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 75 B3)
21:35:53.088 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=117)
21:35:53.089 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 20 44 81 00 00 00 00 D2 C1 01 01 00 61 00 FF DA 41 00 00 0C 18 75 01 04 00 00 42 04 4C 55 4D 49 D2 C1 1D C2)
21:35:53.089 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=32, apiId=44 81, data=FE 20 44 81 00 00 00 00 D2 C1 01 01 00 61 00 FF DA 41 00 00 0C 18 75 01 04 00 00 42 04 4C 55 4D 49 D2 C1 1D C2, checksum=C2, error=false
21:35:53.089 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 75 01 04 00 00 42 04 4C 55 4D 49]
21:35:53.089 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=117, commandId=1]
21:35:53.089 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 49618/1 -> 0/1, cluster=0000, TID=75, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=LUMI]]]
21:35:53.089 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=75, identifiers=[4]]
21:35:53.090 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=3, name=HWVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
21:35:53.090 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=77, identifiers=[3]]
21:35:53.090 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=119, commandId=0]
21:35:53.090 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=119, payload=00 77 00 03 00]
21:35:53.091 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 77 30 1F 05 00 77 00 03 00 10, checksum=10, error=false)
21:35:53.247 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:35:53.247 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 77 41)
21:35:53.247 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=119)
21:35:58.896 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 54 00 F6 21 42 00 00 19 18 00 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 0E)
21:35:58.897 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 54 00 F6 21 42 00 00 19 18 00 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D 0E, checksum=0E, error=false
21:35:58.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 00 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:35:58.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=0, commandId=10]
21:35:58.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=00, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:35:59.248 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 76 40)
21:35:59.248 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=118)
21:36:00.414 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=76, identifiers=[3]]
21:36:00.414 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Hardware version failed
21:36:00.415 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=2, name=StackVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
21:36:00.415 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=78, identifiers=[2]]
21:36:00.415 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=120, commandId=0]
21:36:00.415 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=120, payload=00 78 00 02 00]
21:36:00.416 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 78 30 1F 05 00 78 00 02 00 11, checksum=11, error=false)
21:36:00.525 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:01.090 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=77, identifiers=[3]]
21:36:01.091 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Hardware version failed
21:36:01.091 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=2, name=StackVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
21:36:01.091 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=79, identifiers=[2]]
21:36:01.091 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=121, commandId=0]
21:36:01.091 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=121, payload=00 79 00 02 00]
21:36:01.092 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 79 30 1F 05 00 79 00 02 00 11, checksum=11, error=false)
21:36:01.200 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:05.938 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 66 00 F1 77 42 00 00 07 18 01 0A 00 00 18 01 D2 C1 1D 6E)
21:36:05.938 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 66 00 F1 77 42 00 00 07 18 01 0A 00 00 18 01 D2 C1 1D 6E, checksum=6E, error=false
21:36:05.939 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 01 0A 00 00 18 01]
21:36:05.939 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=1, commandId=10]
21:36:05.939 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=01, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:36:05.939 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Cluster 1030 not found for attribute response
21:36:07.248 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 78 4E)
21:36:07.248 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=120)
21:36:07.249 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 79 4F)
21:36:07.249 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=121)
21:36:08.415 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=78, identifiers=[2]]
21:36:08.416 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Stack version failed
21:36:08.416 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=0, name=ZCLVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
21:36:08.416 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7A, identifiers=[0]]
21:36:08.416 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=122, commandId=0]
21:36:08.416 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=122, payload=00 7A 00 00 00]
21:36:08.417 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 7A 30 1F 05 00 7A 00 00 00 13, checksum=13, error=false)
21:36:08.526 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:09.091 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=79, identifiers=[2]]
21:36:09.092 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Stack version failed
21:36:09.092 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=0, name=ZCLVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
21:36:09.092 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7B, identifiers=[0]]
21:36:09.092 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=123, commandId=0]
21:36:09.093 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=123, payload=00 7B 00 00 00]
21:36:09.093 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 7B 30 1F 05 00 7B 00 00 00 13, checksum=13, error=false)
21:36:09.202 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:10.826 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 66 00 99 B3 42 00 00 19 18 02 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D C3)
21:36:10.826 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=45, apiId=44 81, data=FE 2D 44 81 00 00 00 00 D2 C1 01 01 00 66 00 99 B3 42 00 00 19 18 02 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E D2 C1 1D C3, checksum=C3, error=false
21:36:10.827 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 02 0A 05 00 42 12 6C 75 6D 69 2E 73 65 6E 73 6F 72 5F 6D 6F 74 69 6F 6E]
21:36:10.827 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=2, commandId=10]
21:36:10.827 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Basic: 49618/1 -> 0/1, cluster=0000, TID=02, reports=[Attribute Report: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, attributeValue=lumi.sensor_motion]]
21:36:11.763 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 7A BC)
21:36:11.764 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=122)
21:36:11.764 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 00 00 D2 C1 01 01 00 66 00 FE BE 42 00 00 08 18 7A 01 00 00 00 20 01 D2 C1 1D EA)
21:36:11.764 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 00 00 D2 C1 01 01 00 66 00 FE BE 42 00 00 08 18 7A 01 00 00 00 20 01 D2 C1 1D EA, checksum=EA, error=false
21:36:11.765 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 7A 01 00 00 00 20 01]
21:36:11.765 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=122, commandId=1]
21:36:11.765 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 49618/1 -> 0/1, cluster=0000, TID=7A, records=[ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1]]]
21:36:11.765 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7A, identifiers=[0]]
21:36:11.765 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=6, name=DateCode, dataType=CHARACTER_STRING, lastValue=null]
21:36:11.766 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7C, identifiers=[6]]
21:36:11.766 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=124, commandId=0]
21:36:11.766 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=124, payload=00 7C 00 06 00]
21:36:11.766 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 7C 30 1F 05 00 7C 00 06 00 15, checksum=15, error=false)
21:36:11.884 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:12.440 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 7B BD)
21:36:12.440 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=123)
21:36:12.441 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 00 00 D2 C1 01 01 00 66 00 3E C7 42 00 00 08 18 7B 01 00 00 00 20 01 D2 C1 1D 52)
21:36:12.441 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 00 00 D2 C1 01 01 00 66 00 3E C7 42 00 00 08 18 7B 01 00 00 00 20 01 D2 C1 1D 52, checksum=52, error=false
21:36:12.441 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 7B 01 00 00 00 20 01]
21:36:12.441 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=123, commandId=1]
21:36:12.441 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 49618/1 -> 0/1, cluster=0000, TID=7B, records=[ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1]]]
21:36:12.442 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7B, identifiers=[0]]
21:36:12.442 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=6, name=DateCode, dataType=CHARACTER_STRING, lastValue=null]
21:36:12.443 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7D, identifiers=[6]]
21:36:12.443 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=125, commandId=0]
21:36:12.443 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=125, payload=00 7D 00 06 00]
21:36:12.443 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 00 00 7D 30 1F 05 00 7D 00 06 00 15, checksum=15, error=false)
21:36:12.560 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:13.245 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 7D 4B)
21:36:13.246 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=125)
21:36:18.246 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 7C 4A)
21:36:18.247 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=124)
21:36:19.766 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7C, identifiers=[6]]
21:36:19.767 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Date code failed
21:36:19.767 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=OTA_UPGRADE, id=2, name=CurrentFileVersion, dataType=UNSIGNED_32_BIT_INTEGER, lastValue=null]
21:36:19.767 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [OTA Upgrade: 0/0 -> 49618/1, cluster=0019, TID=7E, identifiers=[2]]
21:36:19.767 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=126, commandId=0]
21:36:19.768 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=25, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=126, payload=08 7E 00 02 00]
21:36:19.768 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 19 00 7E 30 1F 05 08 7E 00 02 00 00, checksum=00, error=false)
21:36:19.877 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:20.443 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [Basic: 0/0 -> 49618/1, cluster=0000, TID=7D, identifiers=[6]]
21:36:20.443 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: Date code failed
21:36:20.444 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=OTA_UPGRADE, id=2, name=CurrentFileVersion, dataType=UNSIGNED_32_BIT_INTEGER, lastValue=null]
21:36:20.444 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [OTA Upgrade: 0/0 -> 49618/1, cluster=0019, TID=7F, identifiers=[2]]
21:36:20.444 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=127, commandId=0]
21:36:20.445 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49618/1, profile=0104, cluster=25, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=127, payload=08 7F 00 02 00]
21:36:20.445 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D2 C1 01 01 19 00 7F 30 1F 05 08 7F 00 02 00 00, checksum=00, error=false)
21:36:20.553 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:26.244 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 7E 48)
21:36:26.245 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=126)
21:36:27.245 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 F0 01 7F 49)
21:36:27.245 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_TRANSACTION_EXPIRED(240), TransID=127)
21:36:27.767 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [OTA Upgrade: 0/0 -> 49618/1, cluster=0019, TID=7E, identifiers=[2]]
21:36:27.768 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: OTA firmware failed
21:36:27.768 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=lumi.sensor_motion, vendor=LUMI, zigbee_networkaddress=49618, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, zigbee_zclversion=1}
21:36:27.769 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D00020B3D32: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:686111d4, label 'LUMI lumi.sensor_motion'
21:36:27.770 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
21:36:27.778 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
21:36:28.444 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ReadAttributesCommand [OTA Upgrade: 0/0 -> 49618/1, cluster=0019, TID=7F, identifiers=[2]]
21:36:28.445 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: OTA firmware failed
21:36:28.445 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D00020B3D32: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=lumi.sensor_motion, zigbee_networkaddress=49618, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_neighbors=[{"joining":"UNKNOWN","address":"0","depth":"0","lqi":"106","macaddress":"00124B0018ED2A82"}], zigbee_devices=[]}
21:36:28.446 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Checking endpoint 1 channels
21:36:28.447 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zigbee:device:686111d4:00158d00020b3d32' has been updated.
21:36:28.447 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Dynamically created 1 channels
21:36:28.447 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Updating thing definition as channels have changed from [] to [zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch]
21:36:28.449 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zigbee:device:686111d4:00158d00020b3d32' has been updated.
21:36:28.450 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Initializing channel zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff@18e98fc5
21:36:28.451 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0/0 -> 49618/1, cluster=0021, TID=80, srcAddress=00158D00020B3D32, srcEndpoint=1, bindCluster=6, dstAddrMode=3, dstAddress=00124B0018ED2A82, dstEndpoint=1]
21:36:28.451 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=33, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=128, payload=00 32 3D 0B 02 00 8D 15 00 01 06 00 03 82 2A ED 18 00 4B 12 00 01]
21:36:28.451 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=32, apiId=24 01, data=FE 20 24 01 D2 C1 00 00 21 00 80 30 1F 16 00 32 3D 0B 02 00 8D 15 00 01 06 00 03 82 2A ED 18 00 4B 12 00 01 11, checksum=11, error=false)
21:36:28.452 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Channel zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch linked - polling started.
21:36:28.453 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'zigbee_device_686111d4_00158d00020b3d32_00158D00020B3D32_1_switch-zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch' has been added.
21:36:28.564 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:31.542 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Channel zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch unlinked - polling stopped.
21:36:31.543 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Channel zigbee:device:686111d4:00158d00020b3d32:00158D00020B3D32_1_switch linked - polling started.
21:36:36.451 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: BindRequest [0/0 -> 49618/1, cluster=0021, TID=80, srcAddress=00158D00020B3D32, srcEndpoint=1, bindCluster=6, dstAddrMode=3, dstAddress=00124B0018ED2A82, dstEndpoint=1]
21:36:36.451 [ERROR] [.converter.ZigBeeConverterSwitchOnoff] - 00158D00020B3D32: Error 0xffff setting client binding
21:36:36.452 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Channel initialisation complete
21:36:36.452 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementBindRequest [0/0 -> 49618/0, cluster=0033, TID=81, startIndex=0]
21:36:36.453 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=49618/0, profile=0000, cluster=51, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=129, payload=00 00]
21:36:36.453 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 D2 C1 00 00 33 00 81 30 1F 02 00 00 A5, checksum=A5, error=false)
21:36:36.562 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
21:36:43.088 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ManagementBindRequest [0/0 -> 49618/0, cluster=0033, TID=81, startIndex=0]
21:36:43.088 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Error getting binding table
21:36:43.090 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Polling initialised at 7238263ms
21:36:43.090 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zigbee:device:686111d4:00158d00020b3d32' changed from OFFLINE: Node has not completed discovery to ONLINE
21:36:43.090 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00158D00020B3D32: Done initialising ZigBee Thing handler
21:36:43.094 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
21:36:43.101 [DEBUG] [rnal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
21:36:47.477 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 69 00 B1 83 44 00 00 07 18 00 0A 00 00 18 01 D2 C1 1D D2)
21:36:47.477 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 69 00 B1 83 44 00 00 07 18 00 0A 00 00 18 01 D2 C1 1D D2, checksum=D2, error=false
21:36:47.477 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 00 0A 00 00 18 01]
21:36:47.478 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=0, commandId=10]
21:36:47.478 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=00, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:36:47.478 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Cluster 1030 not found for attribute response
21:36:52.675 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 6E 00 27 C3 44 00 00 07 18 01 0A 00 00 18 01 D2 C1 1D 02)
21:36:52.676 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 6E 00 27 C3 44 00 00 07 18 01 0A 00 00 18 01 D2 C1 1D 02, checksum=02, error=false
21:36:52.676 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 01 0A 00 00 18 01]
21:36:52.676 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=1, commandId=10]
21:36:52.676 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=01, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:36:52.676 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Cluster 1030 not found for attribute response
21:36:57.751 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 6E 00 1D 01 45 00 00 07 18 02 0A 00 00 18 01 D2 C1 1D F8)
21:36:57.751 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 6E 00 1D 01 45 00 00 07 18 02 0A 00 00 18 01 D2 C1 1D F8, checksum=F8, error=false
21:36:57.752 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 02 0A 00 00 18 01]
21:36:57.752 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=2, commandId=10]
21:36:57.752 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=02, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:36:57.752 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Cluster 1030 not found for attribute response
21:38:52.896 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 73 00 C1 7E 4A 00 00 07 18 03 0A 00 00 18 01 D2 C1 1D 48)
21:38:52.897 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 73 00 C1 7E 4A 00 00 07 18 03 0A 00 00 18 01 D2 C1 1D 48, checksum=48, error=false
21:38:52.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 03 0A 00 00 18 01]
21:38:52.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=3, commandId=10]
21:38:52.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=03, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:38:52.898 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Cluster 1030 not found for attribute response
21:39:42.891 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 7E 00 11 E1 4C 00 00 07 18 04 0A 00 00 18 01 D2 C1 1D 0B)
21:39:42.891 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 04 D2 C1 01 01 00 7E 00 11 E1 4C 00 00 07 18 04 0A 00 00 18 01 D2 C1 1D 0B, checksum=0B, error=false
21:39:42.891 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=49618/1, destinationAddress=0/1, profile=0104, cluster=1030, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 04 0A 00 00 18 01]
21:39:42.891 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=4, commandId=10]
21:39:42.892 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Occupancy sensing: 49618/1 -> 0/1, cluster=0406, TID=04, reports=[Attribute Report: attributeDataType=BITMAP_8_BIT, attributeIdentifier=0, attributeValue=1]]
21:39:42.892 [DEBUG] [m.zsmartsystems.zigbee.ZigBeeEndpoint] - 49618/1: Cluster 1030 not found for attribute response