2019-06-15 16:12:16.227 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/2, cluster=0006, TID=3C, identifiers=[0]]
2019-06-15 16:12:16.228 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 3C FA)
2019-06-15 16:12:16.227 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:16 CEST 2019]
2019-06-15 16:12:16.232 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch updated to OFF
2019-06-15 16:12:16.232 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=60)
2019-06-15 16:12:16.235 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch to OFF
2019-06-15 16:12:22.214 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling...
2019-06-15 16:12:22.216 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch
2019-06-15 16:12:22.220 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:11:22 CEST 2019]
2019-06-15 16:12:22.224 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 38753/3, cluster=0006, TID=3D, identifiers=[0]]
2019-06-15 16:12:22.227 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=61, commandId=0]
2019-06-15 16:12:22.231 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38753/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=61, payload=00 3D 00 00 00]
2019-06-15 16:12:22.234 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 61 97 03 01 06 00 3D 30 1F 05 00 3D 00 00 00 F2, checksum=F2, error=false) 
2019-06-15 16:12:22.420 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:12:22.424 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 3D FB)
2019-06-15 16:12:22.428 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=61)
2019-06-15 16:12:22.434 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 61 97 03 01 00 00 00 25 2F 9B 00 00 08 18 3D 01 00 00 00 10 00 61 97 1D 6D)
2019-06-15 16:12:22.437 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 61 97 03 01 00 00 00 25 2F 9B 00 00 08 18 3D 01 00 00 00 10 00 61 97 1D 6D, checksum=6D, error=false
2019-06-15 16:12:22.441 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38753/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 3D 01 00 00 00 10 00]
2019-06-15 16:12:22.444 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=61, commandId=1]
2019-06-15 16:12:22.448 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 38753/3 -> 0/1, cluster=0006, TID=3D, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:12:22.452 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 38753/3, cluster=0006, TID=3D, identifiers=[0]]
2019-06-15 16:12:22.456 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower
2019-06-15 16:12:22.459 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:11:22 CEST 2019]
2019-06-15 16:12:22.462 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Electrical Measurement: 0/0 -> 38753/3, cluster=0B04, TID=3E, identifiers=[1291]]
2019-06-15 16:12:22.465 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=62, commandId=0]
2019-06-15 16:12:22.468 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38753/3, profile=0104, cluster=2820, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=62, payload=00 3E 00 0B 05]
2019-06-15 16:12:22.470 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 7CB03EAA00B286F8: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:22 CEST 2019]
2019-06-15 16:12:22.471 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 61 97 03 01 04 0B 3E 30 1F 05 00 3E 00 0B 05 F5, checksum=F5, error=false) 
2019-06-15 16:12:22.472 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00B286F8: Channel zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch updated to OFF
2019-06-15 16:12:22.475 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Updating ZigBee channel state zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch to OFF
2019-06-15 16:12:22.669 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:12:22.672 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 3E F8)
2019-06-15 16:12:22.678 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=62)
2019-06-15 16:12:22.680 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1D 44 81 00 00 04 0B 61 97 03 01 00 00 00 13 32 9B 00 00 09 18 3E 01 0B 05 00 29 60 6D 61 97 1D 76)
2019-06-15 16:12:22.682 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=29, apiId=44 81, data=FE 1D 44 81 00 00 04 0B 61 97 03 01 00 00 00 13 32 9B 00 00 09 18 3E 01 0B 05 00 29 60 6D 61 97 1D 76, checksum=76, error=false
2019-06-15 16:12:22.684 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38753/3, destinationAddress=0/1, profile=0104, cluster=2820, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 3E 01 0B 05 00 29 60 6D]
2019-06-15 16:12:22.686 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=62, commandId=1]
2019-06-15 16:12:22.688 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Electrical Measurement: 38753/3 -> 0/1, cluster=0B04, TID=3E, records=[ReadAttributeStatusRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=1291, status=SUCCESS, attributeValue=28000]]]
2019-06-15 16:12:22.691 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Electrical Measurement: 0/0 -> 38753/3, cluster=0B04, TID=3E, identifiers=[1291]]
2019-06-15 16:12:22.691 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 7CB03EAA00B286F8: ZigBee attribute reports ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:12:22 CEST 2019]
2019-06-15 16:12:22.693 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00B286F8: Channel zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower updated to 28000 W
2019-06-15 16:12:22.695 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Updating ZigBee channel state zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower to 28000 W
2019-06-15 16:12:36.978 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 05 45 C4 36 50 01 E2 96 97)
2019-06-15 16:12:36.981 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=5, apiId=45 C4, data=FE 05 45 C4 36 50 01 E2 96 97, checksum=97, error=false
2019-06-15 16:12:36.983 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c4
2019-06-15 16:12:37.297 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 36 50 01 01 00 22 00 CF E4 9B 00 00 07 18 DD 0A 00 00 10 00 E2 96 1C 9C)
2019-06-15 16:12:37.300 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 36 50 01 01 00 22 00 CF E4 9B 00 00 07 18 DD 0A 00 00 10 00 E2 96 1C 9C, checksum=9C, error=false
2019-06-15 16:12:37.304 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 DD 0A 00 00 10 00]
2019-06-15 16:12:37.307 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=221, commandId=10]
2019-06-15 16:12:37.311 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 20534/1 -> 0/1, cluster=0006, TID=DD, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]
2019-06-15 16:12:37.315 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:37 CEST 2019]
2019-06-15 16:12:37.319 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to OFF
2019-06-15 16:12:37.323 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to OFF
2019-06-15 16:12:45.259 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Polling...
2019-06-15 16:12:45.263 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Polling zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch
2019-06-15 16:12:45.266 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:11:42 CEST 2019]
2019-06-15 16:12:45.270 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 38626/11, cluster=0006, TID=3F, identifiers=[0]]
2019-06-15 16:12:45.273 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=63, commandId=0]
2019-06-15 16:12:45.276 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38626/11, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=63, payload=00 3F 00 00 00]
2019-06-15 16:12:45.279 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 E2 96 0B 01 06 00 3F 30 1F 05 00 3F 00 00 00 78, checksum=78, error=false) 
2019-06-15 16:12:45.407 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:12:45.713 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 E2 96 0B 01 00 22 00 89 4B 9C 00 00 08 18 3F 01 00 00 00 10 00 E2 96 1D 8A)
2019-06-15 16:12:45.716 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 E2 96 0B 01 00 22 00 89 4B 9C 00 00 08 18 3F 01 00 00 00 10 00 E2 96 1D 8A, checksum=8A, error=false
2019-06-15 16:12:45.719 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38626/11, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 3F 01 00 00 00 10 00]
2019-06-15 16:12:45.722 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=63, commandId=1]
2019-06-15 16:12:45.725 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 38626/11 -> 0/1, cluster=0006, TID=3F, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:12:45.729 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 38626/11, cluster=0006, TID=3F, identifiers=[0]]
2019-06-15 16:12:45.729 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 01124B001C2E7B92: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:45 CEST 2019]
2019-06-15 16:12:45.733 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 01124B001C2E7B92: Channel zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch updated to OFF
2019-06-15 16:12:45.737 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Updating ZigBee channel state zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch to OFF
2019-06-15 16:12:45.834 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 3F F9)
2019-06-15 16:12:45.836 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=63)
2019-06-15 16:13:13.342 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 19 44 81 00 00 0A 00 36 50 01 01 00 22 00 D4 9C 9D 00 00 05 10 02 00 00 00 E2 96 1C 38)
2019-06-15 16:13:13.345 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=44 81, data=FE 19 44 81 00 00 0A 00 36 50 01 01 00 22 00 D4 9C 9D 00 00 05 10 02 00 00 00 E2 96 1C 38, checksum=38, error=false
2019-06-15 16:13:13.348 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=10, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=10 02 00 00 00]
2019-06-15 16:13:13.351 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=2, commandId=0]
2019-06-15 16:13:13.354 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesCommand [Time: 20534/1 -> 0/1, cluster=000A, TID=02, identifiers=[0]]
2019-06-15 16:13:15.071 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Polling...
2019-06-15 16:13:15.075 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Polling zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_activepower
2019-06-15 16:13:15.079 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:12:11 CEST 2019]
2019-06-15 16:13:15.083 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Electrical Measurement: 0/0 -> 47513/3, cluster=0B04, TID=40, identifiers=[1291]]
2019-06-15 16:13:15.086 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=64, commandId=0]
2019-06-15 16:13:15.090 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=47513/3, profile=0104, cluster=2820, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=64, payload=00 40 00 0B 05]
2019-06-15 16:13:15.093 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 99 B9 03 01 04 0B 40 30 1F 05 00 40 00 0B 05 23, checksum=23, error=false) 
2019-06-15 16:13:15.294 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:15.297 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 C4 99 B9 00 A2)
2019-06-15 16:13:15.300 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 C4, data=FE 03 45 C4 99 B9 00 A2, checksum=A2, error=false
2019-06-15 16:13:15.302 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c4
2019-06-15 16:13:15.305 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 40 86)
2019-06-15 16:13:15.308 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=64)
2019-06-15 16:13:15.312 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1D 44 81 00 00 04 0B 99 B9 03 01 00 15 00 99 B4 9D 00 00 09 18 40 01 0B 05 00 29 60 6D 99 B9 1D 17)
2019-06-15 16:13:15.315 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=29, apiId=44 81, data=FE 1D 44 81 00 00 04 0B 99 B9 03 01 00 15 00 99 B4 9D 00 00 09 18 40 01 0B 05 00 29 60 6D 99 B9 1D 17, checksum=17, error=false
2019-06-15 16:13:15.318 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=47513/3, destinationAddress=0/1, profile=0104, cluster=2820, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 40 01 0B 05 00 29 60 6D]
2019-06-15 16:13:15.321 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=64, commandId=1]
2019-06-15 16:13:15.324 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Electrical Measurement: 47513/3 -> 0/1, cluster=0B04, TID=40, records=[ReadAttributeStatusRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=1291, status=SUCCESS, attributeValue=28000]]]
2019-06-15 16:13:15.327 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Electrical Measurement: 0/0 -> 47513/3, cluster=0B04, TID=40, identifiers=[1291]]
2019-06-15 16:13:15.330 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Polling zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_switch
2019-06-15 16:13:15.330 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 8418260000107128: ZigBee attribute reports ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:13:15 CEST 2019]
2019-06-15 16:13:15.334 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:12 CEST 2019]
2019-06-15 16:13:15.334 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8418260000107128: Channel zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_activepower updated to 28000 W
2019-06-15 16:13:15.337 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 47513/3, cluster=0006, TID=41, identifiers=[0]]
2019-06-15 16:13:15.337 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Updating ZigBee channel state zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_activepower to 28000 W
2019-06-15 16:13:15.340 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=65, commandId=0]
2019-06-15 16:13:15.343 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=47513/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=65, payload=00 41 00 00 00]
2019-06-15 16:13:15.346 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 99 B9 03 01 06 00 41 30 1F 05 00 41 00 00 00 24, checksum=24, error=false) 
2019-06-15 16:13:15.514 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:15.516 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 41 87)
2019-06-15 16:13:15.519 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=65)
2019-06-15 16:13:15.523 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 99 B9 03 01 00 15 00 45 B7 9D 00 00 08 18 41 01 00 00 00 10 00 99 B9 1D FA)
2019-06-15 16:13:15.525 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 99 B9 03 01 00 15 00 45 B7 9D 00 00 08 18 41 01 00 00 00 10 00 99 B9 1D FA, checksum=FA, error=false
2019-06-15 16:13:15.528 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=47513/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 41 01 00 00 00 10 00]
2019-06-15 16:13:15.531 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=65, commandId=1]
2019-06-15 16:13:15.534 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 47513/3 -> 0/1, cluster=0006, TID=41, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:13:15.537 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 47513/3, cluster=0006, TID=41, identifiers=[0]]
2019-06-15 16:13:15.541 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 8418260000107128: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:15 CEST 2019]
2019-06-15 16:13:15.544 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8418260000107128: Channel zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_switch updated to OFF
2019-06-15 16:13:15.547 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Updating ZigBee channel state zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_switch to OFF
2019-06-15 16:13:19.755 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Polling...
2019-06-15 16:13:19.759 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Polling zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch
2019-06-15 16:13:19.763 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:37 CEST 2019]
2019-06-15 16:13:19.768 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=42, identifiers=[0]]
2019-06-15 16:13:19.772 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=66, commandId=0]
2019-06-15 16:13:19.775 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=66, payload=00 42 00 00 00]
2019-06-15 16:13:19.779 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 36 50 01 01 06 00 42 30 1F 05 00 42 00 00 00 60, checksum=60, error=false) 
2019-06-15 16:13:19.903 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:20.187 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 36 50 01 01 00 22 00 64 F0 9D 00 00 08 18 42 01 00 00 00 10 00 E2 96 1C B9)
2019-06-15 16:13:20.190 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 36 50 01 01 00 22 00 64 F0 9D 00 00 08 18 42 01 00 00 00 10 00 E2 96 1C B9, checksum=B9, error=false
2019-06-15 16:13:20.193 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 42 01 00 00 00 10 00]
2019-06-15 16:13:20.196 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=66, commandId=1]
2019-06-15 16:13:20.199 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 20534/1 -> 0/1, cluster=0006, TID=42, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:13:20.202 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=42, identifiers=[0]]
2019-06-15 16:13:20.203 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:20 CEST 2019]
2019-06-15 16:13:20.206 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Polling zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch
2019-06-15 16:13:20.206 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to OFF
2019-06-15 16:13:20.209 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:16 CEST 2019]
2019-06-15 16:13:20.211 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to OFF
2019-06-15 16:13:20.212 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 20534/2, cluster=0006, TID=43, identifiers=[0]]
2019-06-15 16:13:20.215 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=67, commandId=0]
2019-06-15 16:13:20.219 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/2, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=67, payload=00 43 00 00 00]
2019-06-15 16:13:20.223 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 36 50 02 01 06 00 43 30 1F 05 00 43 00 00 00 63, checksum=63, error=false) 
2019-06-15 16:13:20.346 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 42 84)
2019-06-15 16:13:20.348 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=66)
2019-06-15 16:13:20.350 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:20.636 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 43 85)
2019-06-15 16:13:20.637 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=67)
2019-06-15 16:13:20.793 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 36 50 02 01 00 22 00 CF F7 9D 00 00 08 18 43 01 00 00 00 10 00 E2 96 1C 17)
2019-06-15 16:13:20.795 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 36 50 02 01 00 22 00 CF F7 9D 00 00 08 18 43 01 00 00 00 10 00 E2 96 1C 17, checksum=17, error=false
2019-06-15 16:13:20.798 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/2, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 43 01 00 00 00 10 00]
2019-06-15 16:13:20.800 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=67, commandId=1]
2019-06-15 16:13:20.802 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 20534/2 -> 0/1, cluster=0006, TID=43, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:13:20.804 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/2, cluster=0006, TID=43, identifiers=[0]]
2019-06-15 16:13:20.807 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:20 CEST 2019]
2019-06-15 16:13:20.809 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch updated to OFF
2019-06-15 16:13:20.811 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch to OFF
2019-06-15 16:13:22.280 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling...
2019-06-15 16:13:22.286 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch
2019-06-15 16:13:22.290 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:22 CEST 2019]
2019-06-15 16:13:22.296 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 38753/3, cluster=0006, TID=44, identifiers=[0]]
2019-06-15 16:13:22.301 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=68, commandId=0]
2019-06-15 16:13:22.304 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38753/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=68, payload=00 44 00 00 00]
2019-06-15 16:13:22.308 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 61 97 03 01 06 00 44 30 1F 05 00 44 00 00 00 F2, checksum=F2, error=false) 
2019-06-15 16:13:22.525 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:22.527 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 C4 61 97 00 74)
2019-06-15 16:13:22.530 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 C4, data=FE 03 45 C4 61 97 00 74, checksum=74, error=false
2019-06-15 16:13:22.532 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c4
2019-06-15 16:13:22.536 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 44 82)
2019-06-15 16:13:22.538 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=68)
2019-06-15 16:13:22.542 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 61 97 03 01 00 00 00 DA 0C 9E 00 00 08 18 44 01 00 00 00 10 00 61 97 1D CD)
2019-06-15 16:13:22.546 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 61 97 03 01 00 00 00 DA 0C 9E 00 00 08 18 44 01 00 00 00 10 00 61 97 1D CD, checksum=CD, error=false
2019-06-15 16:13:22.550 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38753/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 44 01 00 00 00 10 00]
2019-06-15 16:13:22.553 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=68, commandId=1]
2019-06-15 16:13:22.559 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 38753/3 -> 0/1, cluster=0006, TID=44, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:13:22.566 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 38753/3, cluster=0006, TID=44, identifiers=[0]]
2019-06-15 16:13:22.566 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 7CB03EAA00B286F8: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:22 CEST 2019]
2019-06-15 16:13:22.572 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00B286F8: Channel zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch updated to OFF
2019-06-15 16:13:22.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower
2019-06-15 16:13:22.582 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Updating ZigBee channel state zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch to OFF
2019-06-15 16:13:22.582 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:12:22 CEST 2019]
2019-06-15 16:13:22.588 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Electrical Measurement: 0/0 -> 38753/3, cluster=0B04, TID=45, identifiers=[1291]]
2019-06-15 16:13:22.592 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=69, commandId=0]
2019-06-15 16:13:22.602 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38753/3, profile=0104, cluster=2820, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=69, payload=00 45 00 0B 05]
2019-06-15 16:13:22.608 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 61 97 03 01 04 0B 45 30 1F 05 00 45 00 0B 05 F5, checksum=F5, error=false) 
2019-06-15 16:13:22.813 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:22.817 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 45 83)
2019-06-15 16:13:22.821 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=69)
2019-06-15 16:13:22.827 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1D 44 81 00 00 04 0B 61 97 03 01 00 00 00 62 10 9E 00 00 09 18 45 01 0B 05 00 29 60 6D 61 97 1D 5B)
2019-06-15 16:13:22.831 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=29, apiId=44 81, data=FE 1D 44 81 00 00 04 0B 61 97 03 01 00 00 00 62 10 9E 00 00 09 18 45 01 0B 05 00 29 60 6D 61 97 1D 5B, checksum=5B, error=false
2019-06-15 16:13:22.837 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38753/3, destinationAddress=0/1, profile=0104, cluster=2820, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 45 01 0B 05 00 29 60 6D]
2019-06-15 16:13:22.841 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=69, commandId=1]
2019-06-15 16:13:22.846 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Electrical Measurement: 38753/3 -> 0/1, cluster=0B04, TID=45, records=[ReadAttributeStatusRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=1291, status=SUCCESS, attributeValue=28000]]]
2019-06-15 16:13:22.851 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Electrical Measurement: 0/0 -> 38753/3, cluster=0B04, TID=45, identifiers=[1291]]
2019-06-15 16:13:22.852 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 7CB03EAA00B286F8: ZigBee attribute reports ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:13:22 CEST 2019]
2019-06-15 16:13:22.860 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00B286F8: Channel zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower updated to 28000 W
2019-06-15 16:13:22.869 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Updating ZigBee channel state zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower to 28000 W
2019-06-15 16:13:37.281 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 36 50 01 01 00 22 00 11 C1 9E 00 00 07 18 1A 0A 00 00 10 00 E2 96 1C A5)
2019-06-15 16:13:37.286 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 36 50 01 01 00 22 00 11 C1 9E 00 00 07 18 1A 0A 00 00 10 00 E2 96 1C A5, checksum=A5, error=false
2019-06-15 16:13:37.292 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 1A 0A 00 00 10 00]
2019-06-15 16:13:37.296 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=26, commandId=10]
2019-06-15 16:13:37.301 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 20534/1 -> 0/1, cluster=0006, TID=1A, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]
2019-06-15 16:13:37.307 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:37 CEST 2019]
2019-06-15 16:13:37.312 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to OFF
2019-06-15 16:13:37.316 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to OFF
2019-06-15 16:13:48.785 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Polling...
2019-06-15 16:13:48.790 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Polling zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch
2019-06-15 16:13:48.793 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:12:45 CEST 2019]
2019-06-15 16:13:48.797 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 38626/11, cluster=0006, TID=46, identifiers=[0]]
2019-06-15 16:13:48.800 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=70, commandId=0]
2019-06-15 16:13:48.804 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38626/11, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=70, payload=00 46 00 00 00]
2019-06-15 16:13:48.807 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 E2 96 0B 01 06 00 46 30 1F 05 00 46 00 00 00 78, checksum=78, error=false) 
2019-06-15 16:13:48.938 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:13:49.224 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 46 80)
2019-06-15 16:13:49.226 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=70)
2019-06-15 16:13:49.231 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 E2 96 0B 01 00 22 00 E6 52 9F 00 00 08 18 46 01 00 00 00 10 00 E2 96 1D 86)
2019-06-15 16:13:49.234 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 E2 96 0B 01 00 22 00 E6 52 9F 00 00 08 18 46 01 00 00 00 10 00 E2 96 1D 86, checksum=86, error=false
2019-06-15 16:13:49.238 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38626/11, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 46 01 00 00 00 10 00]
2019-06-15 16:13:49.241 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=70, commandId=1]
2019-06-15 16:13:49.244 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 38626/11 -> 0/1, cluster=0006, TID=46, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:13:49.248 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 38626/11, cluster=0006, TID=46, identifiers=[0]]
2019-06-15 16:13:49.248 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 01124B001C2E7B92: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:49 CEST 2019]
2019-06-15 16:13:49.252 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 01124B001C2E7B92: Channel zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch updated to OFF
2019-06-15 16:13:49.256 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Updating ZigBee channel state zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch to OFF
2019-06-15 16:14:09.018 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Command for channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch --> ON [OnOffType]
2019-06-15 16:14:09.023 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=47]
2019-06-15 16:14:09.028 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=71, commandId=1]
2019-06-15 16:14:09.031 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=71, payload=01 47 01]
2019-06-15 16:14:09.033 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 36 50 01 01 06 00 47 30 1F 03 01 47 01 64, checksum=64, error=false) 
2019-06-15 16:14:09.033 [INFO ] [eclipse.smarthome.model.script.Rules] - Befehl ON empfangen, REFRESH, Status: OFF
2019-06-15 16:14:09.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Command for channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch --> REFRESH [RefreshType]
2019-06-15 16:14:09.058 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:37 CEST 2019]
2019-06-15 16:14:09.062 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=48, identifiers=[0]]
2019-06-15 16:14:09.065 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=72, commandId=0]
2019-06-15 16:14:09.069 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=72, payload=00 48 00 00 00]
2019-06-15 16:14:09.162 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:09.165 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 36 50 01 01 06 00 48 30 1F 05 00 48 00 00 00 60, checksum=60, error=false) 
2019-06-15 16:14:09.293 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:09.578 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 19 44 81 00 00 06 00 36 50 01 01 00 1F 00 9F 49 A0 00 00 05 18 47 0B 01 00 E2 96 1C ED)
2019-06-15 16:14:09.580 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=44 81, data=FE 19 44 81 00 00 06 00 36 50 01 01 00 1F 00 9F 49 A0 00 00 05 18 47 0B 01 00 E2 96 1C ED, checksum=ED, error=false
2019-06-15 16:14:09.584 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 47 0B 01 00]
2019-06-15 16:14:09.587 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=71, commandId=11]
2019-06-15 16:14:09.590 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: 20534/1 -> 0/1, cluster=0006, TID=47, commandIdentifier=1, statusCode=SUCCESS]
2019-06-15 16:14:09.595 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: OnCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=47]
2019-06-15 16:14:09.597 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 48 8E)
2019-06-15 16:14:09.600 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=72)
2019-06-15 16:14:09.603 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 47 81)
2019-06-15 16:14:09.606 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=71)
2019-06-15 16:14:09.609 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 22 44 81 00 00 06 00 36 50 01 01 00 22 00 09 4B A0 00 00 0E 18 3B 0A 00 00 10 01 00 F0 23 47 00 00 07 E2 96 1C 8A)
2019-06-15 16:14:09.612 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=34, apiId=44 81, data=FE 22 44 81 00 00 06 00 36 50 01 01 00 22 00 09 4B A0 00 00 0E 18 3B 0A 00 00 10 01 00 F0 23 47 00 00 07 E2 96 1C 8A, checksum=8A, error=false
2019-06-15 16:14:09.616 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 3B 0A 00 00 10 01 00 F0 23 47 00 00 07]
2019-06-15 16:14:09.619 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=59, commandId=10]
2019-06-15 16:14:09.622 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 20534/1 -> 0/1, cluster=0006, TID=3B, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true, Attribute Report: attributeDataType=UNSIGNED_32_BIT_INTEGER, attributeIdentifier=61440, attributeValue=117440583]]
2019-06-15 16:14:09.625 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - 20534/1: Unknown attribute 61440 in cluster 6
2019-06-15 16:14:09.626 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=true, lastReportTime=Sat Jun 15 16:14:09 CEST 2019]
2019-06-15 16:14:09.628 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 36 50 01 01 00 1F 00 59 4B A0 00 00 08 18 48 01 00 00 00 10 01 E2 96 1C 34)
2019-06-15 16:14:09.631 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to ON
2019-06-15 16:14:09.639 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to ON
2019-06-15 16:14:09.640 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 36 50 01 01 00 1F 00 59 4B A0 00 00 08 18 48 01 00 00 00 10 01 E2 96 1C 34, checksum=34, error=false
2019-06-15 16:14:09.644 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 48 01 00 00 00 10 01]
2019-06-15 16:14:09.652 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=72, commandId=1]
2019-06-15 16:14:09.654 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 20534/1 -> 0/1, cluster=0006, TID=48, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=true]]]
2019-06-15 16:14:09.657 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=48, identifiers=[0]]
2019-06-15 16:14:09.658 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=true, lastReportTime=Sat Jun 15 16:14:09 CEST 2019]
2019-06-15 16:14:09.661 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to ON
2019-06-15 16:14:09.663 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to ON
2019-06-15 16:14:10.207 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 36 50 01 01 00 1F 00 08 53 A0 00 00 07 18 3C 0A 00 00 10 01 E2 96 1C 0A)
2019-06-15 16:14:10.209 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 36 50 01 01 00 1F 00 08 53 A0 00 00 07 18 3C 0A 00 00 10 01 E2 96 1C 0A, checksum=0A, error=false
2019-06-15 16:14:10.211 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 3C 0A 00 00 10 01]
2019-06-15 16:14:10.213 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=60, commandId=10]
2019-06-15 16:14:10.215 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 20534/1 -> 0/1, cluster=0006, TID=3C, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]
2019-06-15 16:14:10.218 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=true, lastReportTime=Sat Jun 15 16:14:10 CEST 2019]
2019-06-15 16:14:10.220 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to ON
2019-06-15 16:14:10.222 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to ON
2019-06-15 16:14:11.429 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1E 44 81 00 00 0C 00 36 50 01 01 00 1F 00 ED 61 A0 00 00 0A 18 3E 0A 55 00 39 06 C9 99 40 E2 96 1C B6)
2019-06-15 16:14:11.432 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=30, apiId=44 81, data=FE 1E 44 81 00 00 0C 00 36 50 01 01 00 1F 00 ED 61 A0 00 00 0A 18 3E 0A 55 00 39 06 C9 99 40 E2 96 1C B6, checksum=B6, error=false
2019-06-15 16:14:11.436 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=12, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 3E 0A 55 00 39 06 C9 99 40]
2019-06-15 16:14:11.438 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=62, commandId=10]
2019-06-15 16:14:11.441 [ERROR] [tworkManager$AFMessageListenerFilter] - Error AF message listener notify.
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.serialization.DefaultDeserializer.readZigBeeType(DefaultDeserializer.java:72) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.zcl.field.AttributeReport.deserialize(AttributeReport.java:100) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.zcl.ZclFieldDeserializer.deserialize(ZclFieldDeserializer.java:73) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand.deserialize(ReportAttributesCommand.java:88) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.receiveZclCommand(ZigBeeNetworkManager.java:808) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.receiveCommand(ZigBeeNetworkManager.java:731) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.ZigBeeDongleTiCc2531.notify(ZigBeeDongleTiCc2531.java:316) ~[202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.ZigBeeNetworkManager$AFMessageListenerFilter.receivedAsynchronousCommand(ZigBeeNetworkManager.java:1288) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.impl.CommandInterfaceImpl.notifyAsynchronousCommand(CommandInterfaceImpl.java:382) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.impl.CommandInterfaceImpl.handlePacket(CommandInterfaceImpl.java:160) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketParser.run(ZToolPacketParser.java:116) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2019-06-15 16:14:13.373 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 19 44 81 00 00 0A 00 36 50 01 01 00 1F 00 A7 79 A0 00 00 05 10 41 00 00 00 E2 96 1C ED)
2019-06-15 16:14:13.375 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=44 81, data=FE 19 44 81 00 00 0A 00 36 50 01 01 00 1F 00 A7 79 A0 00 00 05 10 41 00 00 00 E2 96 1C ED, checksum=ED, error=false
2019-06-15 16:14:13.379 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=10, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=10 41 00 00 00]
2019-06-15 16:14:13.382 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=65, commandId=0]
2019-06-15 16:14:13.385 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesCommand [Time: 20534/1 -> 0/1, cluster=000A, TID=41, identifiers=[0]]
2019-06-15 16:14:18.383 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Polling...
2019-06-15 16:14:18.386 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Polling zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_activepower
2019-06-15 16:14:18.388 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:13:15 CEST 2019]
2019-06-15 16:14:18.390 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Electrical Measurement: 0/0 -> 47513/3, cluster=0B04, TID=49, identifiers=[1291]]
2019-06-15 16:14:18.392 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=73, commandId=0]
2019-06-15 16:14:18.394 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=47513/3, profile=0104, cluster=2820, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=73, payload=00 49 00 0B 05]
2019-06-15 16:14:18.396 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 99 B9 03 01 04 0B 49 30 1F 05 00 49 00 0B 05 23, checksum=23, error=false) 
2019-06-15 16:14:18.567 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:18.569 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 49 8F)
2019-06-15 16:14:18.571 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=73)
2019-06-15 16:14:18.574 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1D 44 81 00 00 04 0B 99 B9 03 01 00 12 00 03 B9 A0 00 00 09 18 49 01 0B 05 00 29 60 6D 99 B9 1D B3)
2019-06-15 16:14:18.575 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=29, apiId=44 81, data=FE 1D 44 81 00 00 04 0B 99 B9 03 01 00 12 00 03 B9 A0 00 00 09 18 49 01 0B 05 00 29 60 6D 99 B9 1D B3, checksum=B3, error=false
2019-06-15 16:14:18.578 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=47513/3, destinationAddress=0/1, profile=0104, cluster=2820, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 49 01 0B 05 00 29 60 6D]
2019-06-15 16:14:18.579 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=73, commandId=1]
2019-06-15 16:14:18.581 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Electrical Measurement: 47513/3 -> 0/1, cluster=0B04, TID=49, records=[ReadAttributeStatusRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=1291, status=SUCCESS, attributeValue=28000]]]
2019-06-15 16:14:18.584 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Electrical Measurement: 0/0 -> 47513/3, cluster=0B04, TID=49, identifiers=[1291]]
2019-06-15 16:14:18.586 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 8418260000107128: ZigBee attribute reports ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:14:18 CEST 2019]
2019-06-15 16:14:18.586 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Polling zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_switch
2019-06-15 16:14:18.589 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8418260000107128: Channel zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_activepower updated to 28000 W
2019-06-15 16:14:18.591 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Updating ZigBee channel state zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_activepower to 28000 W
2019-06-15 16:14:18.591 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:15 CEST 2019]
2019-06-15 16:14:18.595 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 47513/3, cluster=0006, TID=4A, identifiers=[0]]
2019-06-15 16:14:18.598 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=74, commandId=0]
2019-06-15 16:14:18.603 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=47513/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=74, payload=00 4A 00 00 00]
2019-06-15 16:14:18.608 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 99 B9 03 01 06 00 4A 30 1F 05 00 4A 00 00 00 24, checksum=24, error=false) 
2019-06-15 16:14:18.778 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:18.782 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 4A 8C)
2019-06-15 16:14:18.785 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=74)
2019-06-15 16:14:18.790 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 99 B9 03 01 00 15 00 90 BB A0 00 00 08 18 4A 01 00 00 00 10 00 99 B9 1D 15)
2019-06-15 16:14:18.794 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 99 B9 03 01 00 15 00 90 BB A0 00 00 08 18 4A 01 00 00 00 10 00 99 B9 1D 15, checksum=15, error=false
2019-06-15 16:14:18.799 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=47513/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 4A 01 00 00 00 10 00]
2019-06-15 16:14:18.802 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=74, commandId=1]
2019-06-15 16:14:18.806 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 47513/3 -> 0/1, cluster=0006, TID=4A, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:14:18.809 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 47513/3, cluster=0006, TID=4A, identifiers=[0]]
2019-06-15 16:14:18.814 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 8418260000107128: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:18 CEST 2019]
2019-06-15 16:14:18.816 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8418260000107128: Channel zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_switch updated to OFF
2019-06-15 16:14:18.819 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8418260000107128: Updating ZigBee channel state zigbee:device:StickCC2531:8418260000107128:8418260000107128_3_switch to OFF
2019-06-15 16:14:22.346 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling...
2019-06-15 16:14:22.349 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch
2019-06-15 16:14:22.352 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:22 CEST 2019]
2019-06-15 16:14:22.356 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 38753/3, cluster=0006, TID=4B, identifiers=[0]]
2019-06-15 16:14:22.360 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=75, commandId=0]
2019-06-15 16:14:22.363 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38753/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=75, payload=00 4B 00 00 00]
2019-06-15 16:14:22.367 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 61 97 03 01 06 00 4B 30 1F 05 00 4B 00 00 00 F2, checksum=F2, error=false) 
2019-06-15 16:14:22.548 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:22.550 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 4B 8D)
2019-06-15 16:14:22.552 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=75)
2019-06-15 16:14:22.554 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 61 97 03 01 00 00 00 96 E9 A0 00 00 08 18 4B 01 00 00 00 10 00 61 97 1D 55)
2019-06-15 16:14:22.556 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 61 97 03 01 00 00 00 96 E9 A0 00 00 08 18 4B 01 00 00 00 10 00 61 97 1D 55, checksum=55, error=false
2019-06-15 16:14:22.558 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38753/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 4B 01 00 00 00 10 00]
2019-06-15 16:14:22.561 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=75, commandId=1]
2019-06-15 16:14:22.563 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 38753/3 -> 0/1, cluster=0006, TID=4B, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:14:22.565 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 38753/3, cluster=0006, TID=4B, identifiers=[0]]
2019-06-15 16:14:22.565 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 7CB03EAA00B286F8: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:22 CEST 2019]
2019-06-15 16:14:22.567 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00B286F8: Channel zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch updated to OFF
2019-06-15 16:14:22.567 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Polling zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower
2019-06-15 16:14:22.570 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Updating ZigBee channel state zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_switch to OFF
2019-06-15 16:14:22.570 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:13:22 CEST 2019]
2019-06-15 16:14:22.572 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Electrical Measurement: 0/0 -> 38753/3, cluster=0B04, TID=4C, identifiers=[1291]]
2019-06-15 16:14:22.575 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=76, commandId=0]
2019-06-15 16:14:22.579 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38753/3, profile=0104, cluster=2820, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=76, payload=00 4C 00 0B 05]
2019-06-15 16:14:22.582 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 61 97 03 01 04 0B 4C 30 1F 05 00 4C 00 0B 05 F5, checksum=F5, error=false) 
2019-06-15 16:14:22.760 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:22.762 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 4C 8A)
2019-06-15 16:14:22.763 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=76)
2019-06-15 16:14:22.766 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1D 44 81 00 00 04 0B 61 97 03 01 00 00 00 31 EC A0 00 00 09 18 4C 01 0B 05 00 29 60 6D 61 97 1D C3)
2019-06-15 16:14:22.768 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=29, apiId=44 81, data=FE 1D 44 81 00 00 04 0B 61 97 03 01 00 00 00 31 EC A0 00 00 09 18 4C 01 0B 05 00 29 60 6D 61 97 1D C3, checksum=C3, error=false
2019-06-15 16:14:22.771 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38753/3, destinationAddress=0/1, profile=0104, cluster=2820, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 4C 01 0B 05 00 29 60 6D]
2019-06-15 16:14:22.772 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=76, commandId=1]
2019-06-15 16:14:22.775 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Electrical Measurement: 38753/3 -> 0/1, cluster=0B04, TID=4C, records=[ReadAttributeStatusRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=1291, status=SUCCESS, attributeValue=28000]]]
2019-06-15 16:14:22.777 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [Electrical Measurement: 0/0 -> 38753/3, cluster=0B04, TID=4C, identifiers=[1291]]
2019-06-15 16:14:22.777 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 7CB03EAA00B286F8: ZigBee attribute reports ZclAttribute [cluster=ELECTRICAL_MEASUREMENT, id=1291, name=ActivePower, dataType=SIGNED_16_BIT_INTEGER, lastValue=28000, lastReportTime=Sat Jun 15 16:14:22 CEST 2019]
2019-06-15 16:14:22.780 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00B286F8: Channel zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower updated to 28000 W
2019-06-15 16:14:22.782 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B286F8: Updating ZigBee channel state zigbee:device:StickCC2531:7cb03eaa00b286f8:7CB03EAA00B286F8_3_activepower to 28000 W
2019-06-15 16:14:24.542 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Polling...
2019-06-15 16:14:24.545 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Polling zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch
2019-06-15 16:14:24.549 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=true, lastReportTime=Sat Jun 15 16:14:10 CEST 2019]
2019-06-15 16:14:24.553 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=4D, identifiers=[0]]
2019-06-15 16:14:24.557 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=77, commandId=0]
2019-06-15 16:14:24.561 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=77, payload=00 4D 00 00 00]
2019-06-15 16:14:24.564 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 36 50 01 01 06 00 4D 30 1F 05 00 4D 00 00 00 60, checksum=60, error=false) 
2019-06-15 16:14:24.690 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:25.107 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 4D 8B)
2019-06-15 16:14:25.109 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=77)
2019-06-15 16:14:25.113 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 36 50 01 01 00 1F 00 EB 08 A1 00 00 08 18 4D 01 00 00 00 10 01 E2 96 1C C1)
2019-06-15 16:14:25.116 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 36 50 01 01 00 1F 00 EB 08 A1 00 00 08 18 4D 01 00 00 00 10 01 E2 96 1C C1, checksum=C1, error=false
2019-06-15 16:14:25.119 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 4D 01 00 00 00 10 01]
2019-06-15 16:14:25.122 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=77, commandId=1]
2019-06-15 16:14:25.125 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 20534/1 -> 0/1, cluster=0006, TID=4D, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=true]]]
2019-06-15 16:14:25.128 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=4D, identifiers=[0]]
2019-06-15 16:14:25.131 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Polling zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch
2019-06-15 16:14:25.133 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=true, lastReportTime=Sat Jun 15 16:14:25 CEST 2019]
2019-06-15 16:14:25.134 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:20 CEST 2019]
2019-06-15 16:14:25.136 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to ON
2019-06-15 16:14:25.139 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 20534/2, cluster=0006, TID=4E, identifiers=[0]]
2019-06-15 16:14:25.140 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to ON
2019-06-15 16:14:25.142 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=78, commandId=0]
2019-06-15 16:14:25.151 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/2, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=78, payload=00 4E 00 00 00]
2019-06-15 16:14:25.156 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 36 50 02 01 06 00 4E 30 1F 05 00 4E 00 00 00 63, checksum=63, error=false) 
2019-06-15 16:14:25.294 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:25.710 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 4E 88)
2019-06-15 16:14:25.712 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=78)
2019-06-15 16:14:25.714 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 36 50 02 01 00 1F 00 48 10 A1 00 00 08 18 4E 01 00 00 00 10 00 E2 96 1C 7B)
2019-06-15 16:14:25.716 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 36 50 02 01 00 1F 00 48 10 A1 00 00 08 18 4E 01 00 00 00 10 00 E2 96 1C 7B, checksum=7B, error=false
2019-06-15 16:14:25.718 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/2, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 4E 01 00 00 00 10 00]
2019-06-15 16:14:25.720 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=78, commandId=1]
2019-06-15 16:14:25.723 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 20534/2 -> 0/1, cluster=0006, TID=4E, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:14:25.725 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/2, cluster=0006, TID=4E, identifiers=[0]]
2019-06-15 16:14:25.728 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:25 CEST 2019]
2019-06-15 16:14:25.730 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch updated to OFF
2019-06-15 16:14:25.732 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_2_switch to OFF
2019-06-15 16:14:48.881 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Command for channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch --> OFF [OnOffType]
2019-06-15 16:14:48.887 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OffCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=4F]
2019-06-15 16:14:48.892 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=79, commandId=0]
2019-06-15 16:14:48.895 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=79, payload=01 4F 00]
2019-06-15 16:14:48.899 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 36 50 01 01 06 00 4F 30 1F 03 01 4F 00 65, checksum=65, error=false) 
2019-06-15 16:14:48.946 [INFO ] [eclipse.smarthome.model.script.Rules] - Befehl OFF empfangen, REFRESH, Status: ON
2019-06-15 16:14:48.964 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Command for channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch --> REFRESH [RefreshType]
2019-06-15 16:14:48.970 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=true, lastReportTime=Sat Jun 15 16:14:25 CEST 2019]
2019-06-15 16:14:48.976 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=50, identifiers=[0]]
2019-06-15 16:14:48.980 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=80, commandId=0]
2019-06-15 16:14:48.984 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=20534/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=80, payload=00 50 00 00 00]
2019-06-15 16:14:49.028 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:49.031 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 36 50 01 01 06 00 50 30 1F 05 00 50 00 00 00 60, checksum=60, error=false) 
2019-06-15 16:14:49.158 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:49.418 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 4F 89)
2019-06-15 16:14:49.420 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=79)
2019-06-15 16:14:49.424 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 22 44 81 00 00 06 00 36 50 01 01 00 1F 00 F9 30 A2 00 00 0E 18 66 0A 00 00 10 00 00 F0 23 4F 00 00 07 E2 96 1C 6A)
2019-06-15 16:14:49.427 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=34, apiId=44 81, data=FE 22 44 81 00 00 06 00 36 50 01 01 00 1F 00 F9 30 A2 00 00 0E 18 66 0A 00 00 10 00 00 F0 23 4F 00 00 07 E2 96 1C 6A, checksum=6A, error=false
2019-06-15 16:14:49.431 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 66 0A 00 00 10 00 00 F0 23 4F 00 00 07]
2019-06-15 16:14:49.453 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=102, commandId=10]
2019-06-15 16:14:49.455 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 20534/1 -> 0/1, cluster=0006, TID=66, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false, Attribute Report: attributeDataType=UNSIGNED_32_BIT_INTEGER, attributeIdentifier=61440, attributeValue=117440591]]
2019-06-15 16:14:49.457 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - 20534/1: Unknown attribute 61440 in cluster 6
2019-06-15 16:14:49.458 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:49 CEST 2019]
2019-06-15 16:14:49.460 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 19 44 81 00 00 06 00 36 50 01 01 00 1F 00 3B 31 A2 00 00 05 18 4F 0B 00 00 E2 96 1C 3A)
2019-06-15 16:14:49.461 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to OFF
2019-06-15 16:14:49.462 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to OFF
2019-06-15 16:14:49.462 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=44 81, data=FE 19 44 81 00 00 06 00 36 50 01 01 00 1F 00 3B 31 A2 00 00 05 18 4F 0B 00 00 E2 96 1C 3A, checksum=3A, error=false
2019-06-15 16:14:49.464 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 4F 0B 00 00]
2019-06-15 16:14:49.465 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=79, commandId=11]
2019-06-15 16:14:49.468 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: 20534/1 -> 0/1, cluster=0006, TID=4F, commandIdentifier=0, statusCode=SUCCESS]
2019-06-15 16:14:49.470 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: OffCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=4F]
2019-06-15 16:14:49.473 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 50 96)
2019-06-15 16:14:49.475 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=80)
2019-06-15 16:14:49.478 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 36 50 01 01 00 1F 00 AE 31 A2 00 00 08 18 50 01 00 00 00 10 00 E2 96 1C A2)
2019-06-15 16:14:49.480 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 36 50 01 01 00 1F 00 AE 31 A2 00 00 08 18 50 01 00 00 00 10 00 E2 96 1C A2, checksum=A2, error=false
2019-06-15 16:14:49.482 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 50 01 00 00 00 10 00]
2019-06-15 16:14:49.488 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=80, commandId=1]
2019-06-15 16:14:49.490 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 20534/1 -> 0/1, cluster=0006, TID=50, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:14:49.492 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 20534/1, cluster=0006, TID=50, identifiers=[0]]
2019-06-15 16:14:49.493 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:49 CEST 2019]
2019-06-15 16:14:49.496 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to OFF
2019-06-15 16:14:49.498 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to OFF
2019-06-15 16:14:50.398 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 06 00 36 50 01 01 00 1F 00 A3 3D A2 00 00 07 18 67 0A 00 00 10 00 E2 96 1C 97)
2019-06-15 16:14:50.403 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 06 00 36 50 01 01 00 1F 00 A3 3D A2 00 00 07 18 67 0A 00 00 10 00 E2 96 1C 97, checksum=97, error=false
2019-06-15 16:14:50.407 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 67 0A 00 00 10 00]
2019-06-15 16:14:50.411 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=103, commandId=10]
2019-06-15 16:14:50.416 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 20534/1 -> 0/1, cluster=0006, TID=67, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]
2019-06-15 16:14:50.420 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 00158D0003CBE5F4: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:50 CEST 2019]
2019-06-15 16:14:50.425 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0003CBE5F4: Channel zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch updated to OFF
2019-06-15 16:14:50.429 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003CBE5F4: Updating ZigBee channel state zigbee:device:StickCC2531:00158d0003cbe5f4:00158D0003CBE5F4_1_switch to OFF
2019-06-15 16:14:51.461 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1E 44 81 00 00 0C 00 36 50 01 01 00 1F 00 9C 4A A2 00 00 0A 18 69 0A 55 00 39 00 E0 53 3A E2 96 1C 26)
2019-06-15 16:14:51.464 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=30, apiId=44 81, data=FE 1E 44 81 00 00 0C 00 36 50 01 01 00 1F 00 9C 4A A2 00 00 0A 18 69 0A 55 00 39 00 E0 53 3A E2 96 1C 26, checksum=26, error=false
2019-06-15 16:14:51.467 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=20534/1, destinationAddress=0/1, profile=0104, cluster=12, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 69 0A 55 00 39 00 E0 53 3A]
2019-06-15 16:14:51.470 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=105, commandId=10]
2019-06-15 16:14:51.473 [ERROR] [tworkManager$AFMessageListenerFilter] - Error AF message listener notify.
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.serialization.DefaultDeserializer.readZigBeeType(DefaultDeserializer.java:72) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.zcl.field.AttributeReport.deserialize(AttributeReport.java:100) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.zcl.ZclFieldDeserializer.deserialize(ZclFieldDeserializer.java:73) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand.deserialize(ReportAttributesCommand.java:88) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.receiveZclCommand(ZigBeeNetworkManager.java:808) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.receiveCommand(ZigBeeNetworkManager.java:731) ~[201:com.zsmartsystems.zigbee:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.ZigBeeDongleTiCc2531.notify(ZigBeeDongleTiCc2531.java:316) ~[202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.ZigBeeNetworkManager$AFMessageListenerFilter.receivedAsynchronousCommand(ZigBeeNetworkManager.java:1288) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.impl.CommandInterfaceImpl.notifyAsynchronousCommand(CommandInterfaceImpl.java:382) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.impl.CommandInterfaceImpl.handlePacket(CommandInterfaceImpl.java:160) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketParser.run(ZToolPacketParser.java:116) [202:com.zsmartsystems.zigbee.dongle.cc2531:1.1.6]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2019-06-15 16:14:52.310 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Polling...
2019-06-15 16:14:52.312 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Polling zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch
2019-06-15 16:14:52.314 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:13:49 CEST 2019]
2019-06-15 16:14:52.316 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 38626/11, cluster=0006, TID=51, identifiers=[0]]
2019-06-15 16:14:52.319 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=81, commandId=0]
2019-06-15 16:14:52.321 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=38626/11, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=81, payload=00 51 00 00 00]
2019-06-15 16:14:52.323 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 E2 96 0B 01 06 00 51 30 1F 05 00 51 00 00 00 78, checksum=78, error=false) 
2019-06-15 16:14:52.449 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2019-06-15 16:14:52.749 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 51 97)
2019-06-15 16:14:52.752 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=81)
2019-06-15 16:14:52.756 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 E2 96 0B 01 00 1F 00 59 5A A2 00 00 08 18 51 01 00 00 00 10 00 E2 96 1D 26)
2019-06-15 16:14:52.759 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 06 00 E2 96 0B 01 00 1F 00 59 5A A2 00 00 08 18 51 01 00 00 00 10 00 E2 96 1D 26, checksum=26, error=false
2019-06-15 16:14:52.763 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=38626/11, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=18 51 01 00 00 00 10 00]
2019-06-15 16:14:52.766 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=81, commandId=1]
2019-06-15 16:14:52.770 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 38626/11 -> 0/1, cluster=0006, TID=51, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2019-06-15 16:14:52.774 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 01124B001C2E7B92: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false, lastReportTime=Sat Jun 15 16:14:52 CEST 2019]
2019-06-15 16:14:52.774 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ReadAttributesCommand [On/Off: 0/0 -> 38626/11, cluster=0006, TID=51, identifiers=[0]]
2019-06-15 16:14:52.778 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 01124B001C2E7B92: Channel zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch updated to OFF
2019-06-15 16:14:52.781 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 01124B001C2E7B92: Updating ZigBee channel state zigbee:device:StickCC2531:01124b001c2e7b92:01124B001C2E7B92_11_switch to OFF