Zigbee binding

This log is (nearly) fine, but, can you provide the log from a few seconds earlier please. This log shows the reports from the device, but it doesn’t show the command TO the device which would tell us what is going on from the binding perspective. I think we are seeing the response from the command we sent, so it should literally be the previous few lines in the log (but if you provide 1 second or so earlier it should be ok, but feel free to provide a little more if you like).

Thanks a lot, most appreciated!

Here are the few seconds before the lines I already sent:

2018-09-28 09:37:50.856 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 05 00 EE 29 60 00 00 07 18 55 0A 00 00 20 0C B9 C6 1D 07)
2018-09-28 09:37:50.863 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 05 00 EE 29 60 00 00 07 18 55 0A 00 00 20 0C B9 C6 1D 07, checksum=07, error=false
2018-09-28 09:37:50.871 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=50873/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 55 0A 00 00 20 0C]
2018-09-28 09:37:50.878 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=85, commandId=10]
2018-09-28 09:37:50.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 50873/3 -> 0/1, cluster=0008, TID=55, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
2018-09-28 09:37:50.895 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 5B 77 03 01 00 0A 00 73 2A 60 00 00 07 18 32 0A 00 00 20 0C 23 4B 1C B4)
2018-09-28 09:37:50.902 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 5B 77 03 01 00 0A 00 73 2A 60 00 00 07 18 32 0A 00 00 20 0C 23 4B 1C B4, checksum=B4, error=false
2018-09-28 09:37:50.895 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA000018B9: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:50 UTC 2018]
2018-09-28 09:37:50.910 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=30555/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 32 0A 00 00 20 0C]
2018-09-28 09:37:50.916 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA000018B9: Channel zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level updated to 5
2018-09-28 09:37:50.919 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=50, commandId=10]
2018-09-28 09:37:50.930 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA000018B9: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level to 5
2018-09-28 09:37:50.934 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 30555/3 -> 0/1, cluster=0008, TID=32, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
2018-09-28 09:37:50.960 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00002089: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:50 UTC 2018]
2018-09-28 09:37:50.966 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00002089: Channel zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level updated to 5
2018-09-28 09:37:50.972 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00002089: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level to 5
2018-09-28 09:37:51.841 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 07 00 01 36 60 00 00 07 18 56 0A 00 00 20 0C B9 C6 1D F6)
2018-09-28 09:37:51.849 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 07 00 01 36 60 00 00 07 18 56 0A 00 00 20 0C B9 C6 1D F6, checksum=F6, error=false
2018-09-28 09:37:51.858 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=50873/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 56 0A 00 00 20 0C]
2018-09-28 09:37:51.865 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=86, commandId=10]
2018-09-28 09:37:51.879 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 50873/3 -> 0/1, cluster=0008, TID=56, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
2018-09-28 09:37:51.890 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 5B 77 03 01 00 0A 00 77 36 60 00 00 07 18 33 0A 00 00 20 0C 23 4B 1C AD)
2018-09-28 09:37:51.890 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA000018B9: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:51 UTC 2018]
2018-09-28 09:37:51.898 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 5B 77 03 01 00 0A 00 77 36 60 00 00 07 18 33 0A 00 00 20 0C 23 4B 1C AD, checksum=AD, error=false
2018-09-28 09:37:51.904 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA000018B9: Channel zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level updated to 5
2018-09-28 09:37:51.908 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=30555/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 33 0A 00 00 20 0C]
2018-09-28 09:37:51.911 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA000018B9: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level to 5
2018-09-28 09:37:51.912 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=51, commandId=10]
2018-09-28 09:37:51.921 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 30555/3 -> 0/1, cluster=0008, TID=33, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
2018-09-28 09:37:51.928 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00002089: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:51 UTC 2018]
2018-09-28 09:37:51.934 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00002089: Channel zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level updated to 5
2018-09-28 09:37:51.939 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00002089: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level to 5
2018-09-28 09:37:52.296 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 0: Node SVC Discovery NetworkAddressRequest returned null
2018-09-28 09:37:52.301 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 00124B0009EF0176: Node SVC Discovery request NWK_ADDRESS failed. Retry 46, wait 75110ms before retry.
2018-09-28 09:37:52.443 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0000149A: Command for channel zigbee:device:ab2573ab:7cb03eaa0000149a:7CB03EAA0000149A_3_switch_level --> 0
2018-09-28 09:37:52.456 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToLevelWithOnOffCommand [Level Control: 0/0 -> 19235/3, cluster=0008, TID=2A, level=0, transitionTime=10]
2018-09-28 09:37:52.462 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=42, commandId=4]
2018-09-28 09:37:52.468 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=19235/3, profile=0104, cluster=8, addressMode=DEVICE, radius=31, sequence=42, payload=01 2A 04 00 0A 00]
2018-09-28 09:37:52.472 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=16, apiId=24 01, data=FE 10 24 01 23 4B 03 01 08 00 2A 30 1F 06 01 2A 04 00 0A 00 71, checksum=71, error=false)
2018-09-28 09:37:52.606 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-09-28 09:37:52.829 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 05 00 21 42 60 00 00 07 18 57 0A 00 00 20 0C B9 C6 1D A1)
2018-09-28 09:37:52.836 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 05 00 21 42 60 00 00 07 18 57 0A 00 00 20 0C B9 C6 1D A1, checksum=A1, error=false
2018-09-28 09:37:52.845 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=50873/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 57 0A 00 00 20 0C]
2018-09-28 09:37:52.852 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=87, commandId=10]
2018-09-28 09:37:52.860 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 50873/3 -> 0/1, cluster=0008, TID=57, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
2018-09-28 09:37:52.874 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA000018B9: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:52 UTC 2018]
2018-09-28 09:37:52.879 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 5B 77 03 01 00 05 00 86 42 60 00 00 07 18 34 0A 00 00 20 0C B9 C6 1C 37)
2018-09-28 09:37:52.888 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA000018B9: Channel zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level updated to 5
2018-09-28 09:37:52.888 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 5B 77 03 01 00 05 00 86 42 60 00 00 07 18 34 0A 00 00 20 0C B9 C6 1C 37, checksum=37, error=false
2018-09-28 09:37:52.896 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA000018B9: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level to 5
2018-09-28 09:37:52.903 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=30555/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 34 0A 00 00 20 0C]
2018-09-28 09:37:52.914 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=52, commandId=10]
2018-09-28 09:37:52.921 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 30555/3 -> 0/1, cluster=0008, TID=34, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
2018-09-28 09:37:52.927 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00002089: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:52 UTC 2018]
2018-09-28 09:37:52.932 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00002089: Channel zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level updated to 5
2018-09-28 09:37:52.936 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00002089: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level to 5
2018-09-28 09:37:53.034 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 2A EC)
2018-09-28 09:37:53.039 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=42)
2018-09-28 09:37:53.044 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 19 44 81 00 00 08 00 23 4B 03 01 00 0A 00 EA 44 60 00 00 05 08 2A 0B 04 00 23 4B 1D 27)

That all looks fine -:

We see the binding is sending a value of 0 to the device, and we get the updates as the level changes, but it’s not hitting 0 (at least not in this log, and I’m assuming there’s nothing further.

Are you using the latest binding? Or maybe a better question - what version are you using? I think the latest version uses a different command to turn the device OFF, but if you have the latest I’ll take another look at this.

Thanks

I am using stock 2.3 stable for OpenHab, installing the binding through PaperUI.

Here are the version numbers from Karaf:

229 │ Active   │  80 │ 1.0.11                 │ com.zsmartsystems.zigbee
230 │ Active   │  80 │ 1.0.11                 │ com.zsmartsystems.zigbee.dongle.cc2531
231 │ Active   │  80 │ 1.0.11                 │ com.zsmartsystems.zigbee.dongle.ember
232 │ Active   │  80 │ 1.0.11                 │ com.zsmartsystems.zigbee.dongle.telegesis
233 │ Active   │  80 │ 1.0.11                 │ com.zsmartsystems.zigbee.dongle.xbee
234 │ Active   │  80 │ 2.3.0                  │ ZigBee Binding
235 │ Active   │  80 │ 2.3.0                  │ ZigBee CC2531 Binding
236 │ Active   │  80 │ 2.3.0                  │ ZigBee Ember Binding
237 │ Active   │  80 │ 2.3.0                  │ ZigBee Telegesis Binding
238 │ Active   │  80 │ 2.3.0                  │ ZigBee XBee Binding

I would strongly suggest to update to the latest snapshot version. I think this issue will be fixed, and if not, then I’ll take another look at it, but the 2.3 version is quite old now so I can’t really comment too much more than what I have above.

Ok, thanks. I will try that over the weekend.

Have a great weekend!

Hi @chris! :slight_smile: Are you or do you know if anybody is working on getting the ConBee stick supported in the ZigBee binding? :slight_smile:

-R-

I am not, and I don’t think anyone is actively looking at it. A few people have mentioned it, but no-one has done anything (I think).

You might like to comment on this issue -:

Great work @chris! I’m enjoying zigbee binding daily :smile: Any chance of getting Reactive Power (0x050E) added in ZclElectricalMeasurementCluster as well as automatically detected and created as a channel in the binding it self?

Hello.
I have bought a telegesis dongle but it’s always offline. I have read some posts relationed with this and I know that I need to update the zigbee libraries to 1.1.2 (I have 1.1.1) but I don’t know how to do it. Can you help me please?
Thanks.

If you use the latest snapshot, I think you should get 1.1.2.

Do you mean this two files?:

What version of OH are you using? Are you using the latest snapshot? Or are you using another version? If you have the latest snapshot, then you should get the latest ZigBee binding.

Sorry, forget about it. I have found a newer version and now I have the 1.1.2 library versions and now it’s online but only with 19200 bauds.

There is still a message that says that my firmware version is unknown:

image

Is this a problem?

I don’t think this is the latest is it? I think this is about 3 weeks old and I would suggest to ensure you have the latest version as it should resolve your issue.

This is the speed that the dongle works at.

No - it shows that the firmware version is 309C. This is the latest. The UNKNOWN means that there is no update available, and therefore the system can’t confirm that the version is the latest.

Thank you for your quick response ^^

Hey Chris,
thank you for the great work on the binding.
Do you know if this controller for ledstrips will work?
https://www.ikea.com/gb/en/products/lighting/integrated-lighting/trådfri-driver-for-wireless-control-grey-art-50356187/
Greetings from Austria

Hello,
I am also beginner on this topic. I have EFR32 stater kit from silicon labs and raspberrypi 3 for deploying gateway. I installed all the necessary programs needed on raspberrypi and zigbee module for gateway. After adding things on openhab, I am getting device offline. Is their something I am missing.

I guess the obvious answer is “yes” since it doesn’t work :wink:

It’s a bit hard to know what you have done as you’ve not provided a lot of information about your configuration etc - all I can say is the binding is not communicating with the NCP. If you are working with the starter kit, you will need to ensure that the firmware is programmed