Zigbee binding

[Kim_Andersen]

@chris

If you don’t have another stick, I might be able to send you one to borrow while we debug this.

Hmm I dont have another stick… I use the Elelabs Rpi Shield, remeber I have thought about getting an USB stick, since this Rpi shield cant be moved to another platform, if I want to. And it was mainly bought for testing Zigbee only.

@chris

It really would be appreciated. Silabs were not very useful - they agreed the documentation is not usable, and said they would have to look at the sourcecode to work out how it works, but have not come back to me to tell me what the options mean (despite me asking a number of times now). If we can get some logs, then I can use this to prompt them to look at this issue again.

Thanks.

It´s really bad silabs isn´t answering your requests. One would have thought they would be interessted in this community as well.

[chris (Chris Jackson)]

@Kim_Andersen

I dont have another stick

It would be great if you could get one so we can start to debug this

[Kim_Andersen]

@chris

It would be great if you could get one so we can start to debug this

I will. Just need to figure out which one. Tried searching for the Telegesis, but couldn´t fine anyone in europe.

[chris (Chris Jackson)]

These ones are probably the best -:

https://www.digikey.co.uk/product-detail/en/silicon-labs/RD-0002-0201/336-3333-ND/5399115

Unfortunately they don’t come with firmware, so it needs programming. If you wanted to go for this, I could get one and program it for you.

This is fundamentally the same chip as on the Pi board you have.

[Kim_Andersen]

If you say it´s the best, then thats the one we´ll go for
And yes, I would probably need you to get one and programm it, as I have no idea how to…

Just tell me when and how to pay, and I´ll pay!

[oklona (Ole Kristian Lona)]

Hello, Chris!

I just installed some Osram Lightify GU10 bulbs (PAR16 50 TW) in my home. These only dim down to 5%, and completely refuse to turn completely off. The binding only sees a dimmer and color temperature.

Is this something you would know anything about? When googling, I saw that there was some kind of hack at some point to make them turn off when using them with a Hue gateway and binding. (Osram PAR16 50 TW OnOffState not managed correctly?)

I can obviously create logs when I get home, if needed.

[chris (Chris Jackson)]

I have not had any problems with Osram bulbs, and I’ve tested with many different ones, so please provide a debug log and I will have a look.

[oklona (Ole Kristian Lona)]

Ok. I will. For obvious reasons, the bulbs are currently powered off, so I cannot reach them until this evening.

[oklona (Ole Kristian Lona)]

I have a greater problem than I first thought, that needs sorting. Is there any procedure to completely wipe all ZigBee information from OpenHab and the cc2531 stick? I have two devices that always show up in my Inbox, which I have just ignored for a while. After I added one IKEA bulb and the three Osram bulbs, all ZigBee communication in the other side of my living room stopped working.

I did also try out the zigbee2mqtt module with a different stick, on a separate Raspberry, but I assume that shouldn’t interfere with the OH ZigBee binding? The devices that stopped working have never been connected to this second ZigBee environment.

[oklona (Ole Kristian Lona)]

Ok, Chris, would you like the debug log from adding the device, to see properties, or just when adjusting level to 0? Here is the log after I first set it successfully to 44%, and then reduced it to 0. As you can see, it stepped down to 12, and then went to 5. After it goes to 5, it stays there, and to me, it looks like it keeps sending commands to go to 5, but I don’t really know what to look for in these logs…

09:37:53.049 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=44 81, data=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, checksum=27, error=false
09:37:53.058 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=19235/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=08 2A 0B 04 00]
09:37:53.063 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=42, commandId=11]
09:37:53.068 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Level Control: 19235/3 -> 0/1, cluster=0008, TID=2A, commandIdentifier=4, statusCode=SUCCESS]
09:37:53.363 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 23 4B 03 01 00 0A 00 13 49 60 00 00 07 18 29 0A 00 00 20 71 23 4B 1D 94)
09:37:53.370 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 23 4B 03 01 00 0A 00 13 49 60 00 00 07 18 29 0A 00 00 20 71 23 4B 1D 94, checksum=94, error=false
09:37:53.382 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=19235/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 29 0A 00 00 20 71]
09:37:53.387 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=41, commandId=10]
09:37:53.392 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 19235/3 -> 0/1, cluster=0008, TID=29, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=113]]
09:37:53.397 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7CB03EAA0000149A: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=113, lastReportTime=Fri Sep 28 09:37:53 UTC 2018]
09:37:53.403 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7CB03EAA0000149A: Channel zigbee:device:ab2573ab:7cb03eaa0000149a:7CB03EAA0000149A_3_switch_level updated to 44
09:37:53.408 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0000149A: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa0000149a:7CB03EAA0000149A_3_switch_level to 44
09:37:53.826 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 23 4B 03 01 00 0A 00 70 4E 60 00 00 07 18 2A 0A 00 00 20 1E 23 4B 1D 9C)
09:37:53.834 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 23 4B 03 01 00 0A 00 70 4E 60 00 00 07 18 2A 0A 00 00 20 1E 23 4B 1D 9C, checksum=9C, error=false
09:37:53.843 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=19235/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 2A 0A 00 00 20 1E]
09:37:53.850 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=42, commandId=10]
09:37:53.858 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 19235/3 -> 0/1, cluster=0008, TID=2A, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=30]]
09:37:53.868 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 07 00 8C 4E 60 00 00 07 18 58 0A 00 00 20 0C B9 C6 1D 0D)
09:37:53.868 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7CB03EAA0000149A: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=30, lastReportTime=Fri Sep 28 09:37:53 UTC 2018]
09:37:53.880 [DEBUG] [531.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 8C 4E 60 00 00 07 18 58 0A 00 00 20 0C B9 C6 1D 0D, checksum=0D, error=false
09:37:53.887 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7CB03EAA0000149A: Channel zigbee:device:ab2573ab:7cb03eaa0000149a:7CB03EAA0000149A_3_switch_level updated to 12
09:37:53.892 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=50873/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 58 0A 00 00 20 0C]
09:37:53.897 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0000149A: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa0000149a:7CB03EAA0000149A_3_switch_level to 12
09:37:53.901 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=88, commandId=10]
09:37:53.914 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 50873/3 -> 0/1, cluster=0008, TID=58, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
09:37:53.928 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 5B 77 03 01 00 05 00 B3 4E 60 00 00 07 18 35 0A 00 00 20 0C B9 C6 1C 0F)
09:37:53.935 [DEBUG] [531.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 B3 4E 60 00 00 07 18 35 0A 00 00 20 0C B9 C6 1C 0F, checksum=0F, error=false
09:37:53.928 [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:53 UTC 2018]
09:37:53.941 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=30555/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 35 0A 00 00 20 0C]
09:37:53.945 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7CB03EAA000018B9: Channel zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level updated to 5
09:37:53.947 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=53, commandId=10]
09:37:53.951 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA000018B9: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa000018b9:7CB03EAA000018B9_3_switch_level to 5
09:37:53.954 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 30555/3 -> 0/1, cluster=0008, TID=35, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
09:37:53.962 [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:53 UTC 2018]
09:37:53.969 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7CB03EAA00002089: Channel zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level updated to 5
09:37:53.974 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00002089: Updating ZigBee channel state zigbee:device:ab2573ab:7cb03eaa00002089:7CB03EAA00002089_3_switch_level to 5
09:37:54.849 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 23 4B 03 01 00 0A 00 4A 5A 60 00 00 07 18 2B 0A 00 00 20 0C 23 4B 1D A1)
09:37:54.856 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=27, apiId=44 81, data=FE 1B 44 81 00 00 08 00 23 4B 03 01 00 0A 00 4A 5A 60 00 00 07 18 2B 0A 00 00 20 0C 23 4B 1D A1, checksum=A1, error=false
09:37:54.865 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=19235/3, destinationAddress=0/1, profile=0104, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 2B 0A 00 00 20 0C]
09:37:54.872 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=43, commandId=10]
09:37:54.883 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 19235/3 -> 0/1, cluster=0008, TID=2B, reports=[Attribute Report: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=12]]
09:37:54.892 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1B 44 81 00 00 08 00 B9 C6 03 01 00 05 00 9F 5A 60 00 00 07 18 59 0A 00 00 20 0C B9 C6 1D 09)
09:37:54.892 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7CB03EAA0000149A: ZigBee attribute reports ZclAttribute [cluster=LEVEL_CONTROL, id=0, name=CurrentLevel, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=12, lastReportTime=Fri Sep 28 09:37:54 UTC 2018]
09:37:54.905 [DEBUG] [531.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 9F 5A 60 00 00 07 18 59 0A 00 00 20 0C B9 C6 1D 09, checksum=09, error=false
09:37:54.908 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7CB03EAA0000149A: Channel zigbee:device:ab2573ab:7cb03eaa0000149a:7CB03EAA0000149A_3_switch_level updated to 5

[chris (Chris Jackson)]

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).

[oklona (Ole Kristian Lona)]

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)

[chris (Chris Jackson)]

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.

[oklona (Ole Kristian Lona)]

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

[chris (Chris Jackson)]

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.

[oklona (Ole Kristian Lona)]

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

Have a great weekend!

[rogerrem (Roger)]

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

-R-

[chris (Chris Jackson)]

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 -:

Issue: ConBee/RaspBee Support

[iamb (Bendik)]

Great work @chris! I’m enjoying zigbee binding daily 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?

[Kergilo (Antonio Jose)]

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.